Aug 27 20:00:00 rivo mpd[3791]: 2026-08-27T20:00:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 20:00:01 rivo volumio[3236]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 20:00:01 rivo volumio[3236]: info: [1787853601240] CoreMusicLibrary::Adding element Last_100 Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:01 rivo volumio[3236]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 27 20:00:01 rivo volumio[3236]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 27 20:00:01 rivo volumio[3236]: info: Starting RAAT Plugin Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 27 20:00:01 rivo volumio[3236]: info: Additional UI Settings Added for plugin music_service/raat Aug 27 20:00:01 rivo volumio[3236]: info: Registering DSP Elements listener and retrieving current ones Aug 27 20:00:01 rivo volumio[3236]: info: Additional DSP elements updated Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:01 rivo volumio[3236]: info: Updating RAAT Signal Path Aug 27 20:00:01 rivo volumio[3236]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 20:00:01 rivo sudo[3798]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 20:00:01 rivo sudo[3798]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo volumio[3236]: info: Streaming services startup Aug 27 20:00:01 rivo volumio[3236]: info: Starting Streaming Daemon Aug 27 20:00:01 rivo sudo[3798]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 20:00:01 rivo volumio[3236]: info: [1787853601352] CoreMusicLibrary::Adding element Webradio Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:01 rivo sudo[3803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 20:00:01 rivo sudo[3803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 20:00:01 rivo volumio[3236]: info: Initializing BBC Radios Aug 27 20:00:01 rivo sudo[3809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 20:00:01 rivo sudo[3809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 20:00:01 rivo sudo[3811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3803]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:00:01 rivo volumio[3236]: info: [now-playing] ConfigUpdater: config is up to date. Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 27 20:00:01 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 20:00:01 rivo sudo[3809]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo volumio[3236]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:01 rivo sudo[3811]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo volumio[3236]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 27 20:00:01 rivo volumio[3236]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 27 20:00:01 rivo volumio[3236]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 27 20:00:01 rivo volumio[3236]: info: Touch Event Listener Process Starting Aug 27 20:00:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 27 20:00:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 27 20:00:01 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 20:00:01 rivo volumio[3236]: info: [1787853601637] CoreMusicLibrary::Adding element QOBUZ Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:01 rivo volumio[3236]: Cannot find translation for source QOBUZ Aug 27 20:00:01 rivo volumio[3236]: info: Refreshing QOBUZ token Aug 27 20:00:01 rivo sudo[3834]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 27 20:00:01 rivo sudo[3834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo volumio[3236]: info: Adding inputs REST Endpoints Aug 27 20:00:01 rivo volumio[3236]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 27 20:00:01 rivo volumio[3236]: info: Scanning Audio Inputs Aug 27 20:00:01 rivo volumio[3236]: info: Checking against Known Cards name Aug 27 20:00:01 rivo volumio[3236]: info: Checking against Known Cards name Aug 27 20:00:01 rivo volumio[3236]: info: Checking against Known Cards name Aug 27 20:00:01 rivo volumio[3236]: info: Checking against Known Cards name Aug 27 20:00:01 rivo volumio[3236]: info: Checking against Known Cards name Aug 27 20:00:01 rivo volumio[3236]: info: Adding Server instance for streaming Aug 27 20:00:01 rivo volumio[3236]: info: Adding TIDAL to Browse Sources Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 20:00:01 rivo volumio[3236]: info: [1787853601713] CoreMusicLibrary::Adding element TIDAL Aug 27 20:00:01 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:01 rivo volumio[3236]: Cannot find translation for source QOBUZ Aug 27 20:00:01 rivo volumio[3236]: Cannot find translation for source TIDAL Aug 27 20:00:01 rivo volumio[3236]: info: Refreshing TIDAL token Aug 27 20:00:01 rivo sudo[3845]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 27 20:00:01 rivo sudo[3845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3845]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo sudo[3848]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 27 20:00:01 rivo sudo[3848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3848]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo sudo[3851]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 27 20:00:01 rivo sudo[3851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3851]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo sudo[3854]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 27 20:00:01 rivo sudo[3854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo sudo[3854]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:01 rivo volumio[3236]: info: Apply VIM3L Onboard LEDs Settings Aug 27 20:00:01 rivo volumio[3236]: info: Turning On Onboard LEDs Aug 27 20:00:01 rivo volumio[3236]: info: Starting CEC Event listener Aug 27 20:00:01 rivo sudo[3858]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 20:00:01 rivo sudo[3858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:01 rivo volumio[3236]: info: Loading i18n strings for locale en Aug 27 20:00:02 rivo sudo[3858]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:02 rivo volumio[3236]: info: Volumio Calling Home Aug 27 20:00:02 rivo sudo[3862]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 20:00:02 rivo sudo[3862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:02 rivo sudo[3862]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:02 rivo systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 20:00:02 rivo sudo[3753]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:02 rivo sudo[3769]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:02 rivo volumio[3236]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 27 20:00:02 rivo volumio[3236]: (node:3236) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 27 20:00:02 rivo volumio[3236]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 27 20:00:02 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 20:00:02 rivo volumio[3236]: info: [now-playing] App is listening on port 4004. Aug 27 20:00:02 rivo volumio[3236]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 27 20:00:02 rivo volumio[3236]: info: Serial port opened successfully Aug 27 20:00:02 rivo volumio[3236]: info: Sending serial start messages Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: Reporting MCU Network Status: 1 Aug 27 20:00:02 rivo volumio[3236]: info: MP1 GPIO: Signalled system ready via GPIO Aug 27 20:00:02 rivo volumio[3236]: info: minidlna: Found miniDLNA version 1.3.0 Aug 27 20:00:02 rivo volumio[3236]: info: minidlna: Starting minidlna.service Aug 27 20:00:02 rivo volumio[3236]: error: Cannot start Volumio Streaming Daemon Aug 27 20:00:02 rivo volumio[3236]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 20:00:02 rivo volumio[3236]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 20:00:02 rivo volumio[3236]: info: RAAT Albumart path created successfully Aug 27 20:00:02 rivo sudo[3876]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 27 20:00:02 rivo sudo[3876]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:02 rivo volumio[3236]: info: MPD Permissions set Aug 27 20:00:02 rivo volumio[3236]: info: MPD Permissions set Aug 27 20:00:02 rivo volumio[3236]: info: Volumio called home Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:02 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:02 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:02 rivo systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Aug 27 20:00:02 rivo sudo[3876]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:02 rivo volumio[3236]: error: MPD error: The expression evaluated to a falsy value: Aug 27 20:00:02 rivo volumio[3236]: assert.ok(self.idling) Aug 27 20:00:02 rivo volumio[3236]: error: The expression evaluated to a falsy value: Aug 27 20:00:02 rivo volumio[3236]: assert.ok(self.idling) Aug 27 20:00:02 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 4 Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:02 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 27 20:00:02 rivo volumio[3236]: info: Setting Device Volume Override Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 20:00:03 rivo volumio[3236]: info: Updating Volume Controller Parameters: Device: 5 Name: X-SABRE Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 20:00:03 rivo volumio[3236]: info: Disabling external Volume Control Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:03 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 20:00:03 rivo volumio[3236]: info: Setting Additional System Software info: Hardware Revision: 2.0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 20:00:03 rivo volumio[3236]: info: Setting HW Firmware info: undefined Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 20:00:03 rivo volumio[3236]: info: Setting HW Version info: 2.0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 20:00:03 rivo volumio[3236]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.5 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 20:00:03 rivo volumio[3236]: info: Setting HW Firmware info: 0.3.5 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 20:00:03 rivo volumio[3236]: info: Setting HW Version info: 2.0 Aug 27 20:00:03 rivo volumio[3236]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 27 20:00:03 rivo volumio[3236]: info: MCU Signalled Headphone Mode Disabled Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 27 20:00:03 rivo volumio[3236]: info: Reporting Headphone State: false Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:03 rivo volumio[3236]: info: Updating RAAT Signal Path Aug 27 20:00:03 rivo volumio[3236]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 20:00:03 rivo volumio[3236]: info: MCU Signalled Sleep Mode Disabled Aug 27 20:00:03 rivo volumio[3236]: info: Enabling Advanced system settings configuration Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 27 20:00:03 rivo volumio[3236]: info: Additional UI Settings Added for plugin music_service/inputs Aug 27 20:00:03 rivo volumio[3236]: info: MCU Signalled Auto Boot Mode On Power Disabled Aug 27 20:00:03 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: error: updateQueue error: null Aug 27 20:00:03 rivo sudo[3897]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 27 20:00:03 rivo sudo[3897]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 27 20:00:03 rivo sudo[3897]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 5 Aug 27 20:00:03 rivo volumio[3236]: info: minidlna: systemctl start minidlna.service succeeded. Aug 27 20:00:03 rivo volumio[3236]: info: MPD running with PID3791 Aug 27 20:00:03 rivo volumio[3236]: ,establishing connection Aug 27 20:00:03 rivo volumio[3236]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 6 Aug 27 20:00:03 rivo systemd[1]: minidlna.service: Deactivated successfully. Aug 27 20:00:03 rivo sudo[3902]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 27 20:00:03 rivo sudo[3902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo sudo[3902]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Aug 27 20:00:03 rivo volumio[3236]: info: Starting Shairport Sync Aug 27 20:00:03 rivo volumio[3236]: info: Starting Shairport Sync Aug 27 20:00:03 rivo volumio[3236]: info: Starting Shairport Sync Aug 27 20:00:03 rivo sudo[3906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 20:00:03 rivo sudo[3906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo volumio[3236]: error: updateQueue error: null Aug 27 20:00:03 rivo sudo[3908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 20:00:03 rivo sudo[3908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo volumio[3236]: info: Adding Inputs via Serial API Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo sudo[3910]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 20:00:03 rivo sudo[3910]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 8 Aug 27 20:00:03 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 20:00:03 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::servicePushState Aug 27 20:00:03 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:03 rivo volumio[3236]: Cannot find translation for source QOBUZ Aug 27 20:00:03 rivo volumio[3236]: Cannot find translation for source TIDAL Aug 27 20:00:03 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 20:00:03 rivo sudo[3906]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Aug 27 20:00:03 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio5-onboarding[3775]: time=2026-08-27T20:00:03.415+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 27 20:00:03 rivo sudo[3908]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 20:00:03 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 20:00:03 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 20:00:03 rivo volumio5-onboarding[3775]: time=2026-08-27T20:00:03.430+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 20:00:03 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 20:00:03 rivo sudo[3910]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo volumio[3236]: info: Shairport-Sync Started Aug 27 20:00:03 rivo volumio[3236]: Error adding Membership: Error: addMembership EINVAL Aug 27 20:00:03 rivo volumio[3236]: info: Shairport-Sync Started Aug 27 20:00:03 rivo volumio[3236]: info: Executing endpoint qc_getconfig Aug 27 20:00:03 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.595 [3824.3824] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 20:00:03 rivo volumio[3236]: info: Shairport-Sync Started Aug 27 20:00:03 rivo volumio[3236]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 20:00:03 rivo volumio[3236]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 10 Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 11 Aug 27 20:00:03 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 12 Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10:3000 from 192.168.0.25 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.750 [3824.3824] INFO VolumeManager: [0xac3baf68]: Setting new playback volume: 75 Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.750 [3824.3824] INFO VolumeManager: [0xac3baf68]: Setting new mute state: 0 Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.750 [3824.3824] INFO AudioStreamManager: [0xac3bacc0]: Setting new audio download buffer size: 1048576 Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.750 [3824.3824] INFO QobuzConnect: [0xac3bb830]: Client initialized! Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.750 [3824.3824] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.764 [3824.3824] INFO LocalConfigManager: [0xac3ba9e8]: Starting Local Configuration server Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.764 [3824.3824] INFO SampleApp: Starting Local configuration server Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.765 [3824.3824] INFO SampleApp: Connected to UNIX socket client 0xac3a5908 Aug 27 20:00:03 rivo qobuz-connect[3824]: 20260827 20:00:03.765 [3824.3824] INFO SampleApp: Playback volume changed: 75 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10:3000 from 192.168.0.25 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 13 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:03 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:03 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 27 20:00:03 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 27 20:00:03 rivo sudo[3942]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 27 20:00:03 rivo sudo[3942]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo sudo[3947]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 20:00:03 rivo sudo[3947]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo sudo[3954]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 20:00:03 rivo sudo[3954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: Error getting the SSID Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: Error getting the SSID Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:03 rivo sudo[3954]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:03 rivo sudo[3947]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo sudo[3942]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo sudo[3960]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 27 20:00:04 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: Error getting the SSID Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo kernel: dhd_ioctl_entry: Interface is down Aug 27 20:00:04 rivo sudo[3968]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 20:00:04 rivo sudo[3968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo sudo[3971]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 20:00:04 rivo sudo[3971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo sudo[3968]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo sudo[3971]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:04 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:04 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:04 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:04 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:04 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:04 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10:3000 from 192.168.0.25 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 14 Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 20:00:04 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 20:00:04 rivo volumio[3236]: info: TidalConnect service stoped! Aug 27 20:00:04 rivo volumio[3236]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 20:00:04 rivo volumio[3236]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 20:00:04 rivo sudo[4003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 20:00:04 rivo sudo[4003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo volumio[3236]: info: Turning Off Onboard LEDs Aug 27 20:00:04 rivo sudo[4007]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 20:00:04 rivo sudo[4007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 20:00:04 rivo sudo[4003]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo sudo[4007]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo sudo[4014]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 20:00:04 rivo sudo[4014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 20:00:04 rivo sudo[4014]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:04 rivo volumio[3236]: info: Onboard LEDs initialized Aug 27 20:00:05 rivo volumio[3236]: info: Executing endpoint tc_getconfig Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 20:00:05 rivo vtcs[4018]: STARTING TidalConnect services, version: 1.6.1 Aug 27 20:00:05 rivo vtcs[4018]: STARTED TidalConnect services. Aug 27 20:00:05 rivo volumio[3236]: info: Executing endpoint tc_connect Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 20:00:05 rivo volumio[3236]: info: Connecting to TidalConnect Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::servicePushState Aug 27 20:00:05 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::servicePushState Aug 27 20:00:05 rivo volumio[3236]: info: CoreStateMachine::pushState Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::volumioPushState Aug 27 20:00:05 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:05 rivo volumio[3236]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Aug 27 20:00:05 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.16 UA: Mozilla/5.0 (iPad; CPU OS 9_3_5 like Mac OS X) AppleWebKit/601.1.46 (KHTML, like Gecko) Mobile/13G36 Engine version: 3 Transport: polling Total Clients: 14 Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:06 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:06 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 20:00:06 rivo volumio[3236]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 20:00:06 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:06 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:06 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:06 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:06 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:06 rivo volumio[3236]: info: Listing playlists Aug 27 20:00:06 rivo volumio[3236]: info: MRS: Getting audio outputs on start Aug 27 20:00:06 rivo volumio[3236]: info: MRS: Requesting all other devices output Aug 27 20:00:07 rivo volumio[3236]: info: TidalConnect service started! Aug 27 20:00:07 rivo volumio[3236]: verbose: New Socket.io Connection to 192.168.0.10 from 192.168.0.25 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 15 Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:07 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:07 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetQueue Aug 27 20:00:07 rivo volumio[3236]: info: CoreStateMachine::getQueue Aug 27 20:00:07 rivo volumio[3236]: info: CorePlayQueue::getQueue Aug 27 20:00:07 rivo volumio[3236]: info: Listing playlists Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 20:00:07 rivo volumio[3236]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 27 20:00:07 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:07 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:07 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:07 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:07 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:08 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 20:00:08 rivo kernel: fb: mem_free_work, free memory: addr:800000 Aug 27 20:00:08 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 20:00:09 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 20:00:09 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 20:00:09 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 20:00:09 rivo sudo[3748]: pam_unix(sudo:session): session closed for user root Aug 27 20:00:09 rivo volumio[3236]: info: Upmpdcli Daemon Started Aug 27 20:00:09 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 20:00:09 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:09 rivo volumio[3236]: info: Executing endpoint metavolumio Aug 27 20:00:09 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 27 20:00:10 rivo volumio[3236]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 20:00:12 rivo volumio-remote-updater[2957]: [2026-08-27 20:00:12] [connect] Successful connection Aug 27 20:00:13 rivo volumio[3236]: info: Checking for updated MCU Firmware Aug 27 20:00:13 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 20:00:13 rivo volumio[3236]: info: Firware on device is on latest version, no need to update Aug 27 20:00:13 rivo volumio-remote-updater[2957]: [2026-08-27 20:00:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787853612 101 Aug 27 20:00:13 rivo volumio[3236]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 16 Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:17 rivo volumio[3236]: error: Failed request for metavolumio API Aug 27 20:00:24 rivo volumio[3236]: info: Discovery: adding 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 20:00:24 rivo volumio[3236]: info: Discovery: Found device Rivo Aug 27 20:00:24 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:24 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:24 rivo volumio[3236]: info: MRS: Pushing multiroomSync output for this device Aug 27 20:00:24 rivo volumio[3236]: info: MRS: Pushing multiroomSync output Aug 27 20:00:24 rivo volumio[3236]: info: Adding audio output: Aug 27 20:00:24 rivo volumio[3236]: info: Adding audio output: Aug 27 20:00:24 rivo volumio[3236]: info: Discovery: this is already registered, 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 20:00:24 rivo volumio[3236]: info: Discovery: Found device Rivo Aug 27 20:00:24 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:24 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:30 rivo volumio[3236]: info: Access Token successfully retrieved Aug 27 20:00:30 rivo volumio[3236]: error: Hi Res Audio Failed Login: [object Object] Aug 27 20:00:32 rivo volumio[3236]: info: Access Token successfully retrieved Aug 27 20:00:32 rivo volumio[3236]: info: Stopping AccessToken refresher cron for QOBUZ Aug 27 20:00:32 rivo volumio[3236]: info: AccessToken refresher cron started for QOBUZ Aug 27 20:00:32 rivo volumio[3236]: info: Adding QOBUZ REST API Endpoints Aug 27 20:00:32 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Aug 27 20:00:32 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 20:00:32 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:00:32 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 20:00:32 rivo volumio[3236]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Aug 27 20:00:32 rivo volumio[3236]: info: ------------------------------------------- Aug 27 20:00:32 rivo volumio[3236]: info: ----- MyVolumio plugins startup ---- Aug 27 20:00:32 rivo volumio[3236]: info: ------------------------------------------- Aug 27 20:00:32 rivo volumio[3236]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 20:00:33 rivo volumio5-onboarding[3775]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 27 20:00:33 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 27 20:00:33 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 27 20:00:33 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 27 20:00:33 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 20:00:33 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 20:00:33 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:33.823+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 20:00:34 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 27 20:00:39 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Aug 27 20:00:40 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 18 Aug 27 20:00:40 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:40 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:40 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:40 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:40 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:40 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:40 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:40 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:40.702+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 27 20:00:40 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:40.720+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 20:00:41 rivo upmpdcli[4524]: writing RSA key Aug 27 20:00:42 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:42 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:42 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:42 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:42 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:42 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:42 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:44 rivo volumio-remote-updater[2957]: Test mode enabled Aug 27 20:00:44 rivo volumio-remote-updater[2957]: Alpha mode disabled Aug 27 20:00:44 rivo volumio-remote-updater[2957]: Alpha legacy test mode disabled Aug 27 20:00:47 rivo volumio[3236]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 20:00:47 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 20:00:49 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 20:00:51 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 20:00:53 rivo volumio[3236]: info: MRS: Found cast device: CHSTB02-88837a1d9d2011fd891ba3df03e82fab Aug 27 20:00:53 rivo volumio[3236]: info: Adding audio output: Aug 27 20:00:53 rivo volumio[3236]: info: MRS: Found cast device: CHSTB02-2c07cb952969f3ad2bbb27b23c87610d Aug 27 20:00:53 rivo volumio[3236]: info: Adding audio output: Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 20:00:53 rivo volumio[3236]: error: MyVolumio Plugin failed to start in a timely fashion Aug 27 20:00:53 rivo volumio[3236]: [Metrics] CommandRouter: 82s 925.03ms Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::Close All Modals sent Aug 27 20:00:53 rivo volumio[3236]: info: CoreCommandRouter::Close All Modals sent Aug 27 20:00:55 rivo volumio[3236]: info: Received Get System Info Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 20:00:55 rivo volumio[3236]: info: Discovery: Getting this device information Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::volumioGetState Aug 27 20:00:55 rivo volumio[3236]: info: CorePlayQueue::getTrack 0 Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 20:00:55 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 20:00:58 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 20:00:58 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 20:00:58 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:58.025+02:00 level=INFO msg="enabling local network discovery" Aug 27 20:00:58 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:58.026+02:00 level=INFO msg="enabling BLE discovery" Aug 27 20:00:59 rivo volumio5-onboarding[4482]: time=2026-08-27T20:00:59.017+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 20:01:00 rivo volumio[3236]: info: Successfully retrieved User Session From TIDAL Aug 27 20:01:00 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Aug 27 20:01:07 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38, ...) Aug 27 20:01:07 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:07.368+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.25:53221 Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.127+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.0.25:53221 Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.127+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.0.25:53221 Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.145+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.25:53224 Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.171+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.171+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.25:53224 @ 0x25248a0" latency=25.26601ms timeout=20s included=[] excluded=[] Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.172+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.25:53224 @ 0x25248a0" Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.254+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=http://pushupdates.volumio.org duration=78.548434ms Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.649+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=http://cddb.volumio.org duration=470.592939ms Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.770+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=598.250955ms Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.911+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=http://plugins.volumio.org duration=732.727271ms Aug 27 20:01:08 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:08.920+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=749.098021ms Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.216+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://securetoken.googleapis.com duration=1.04226227s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.224+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://database.volumio.cloud duration=1.047702402s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.248+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=1.070151013s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.274+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://www.googleapis.com duration=1.102283491s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.316+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=1.140351921s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.319+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.144663521s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.351+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.175796205s Aug 27 20:01:09 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:09.669+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Aug 27 20:01:09 rivo dbus-daemon[2941]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.28" (uid=0 pid=4482 comm="/usr/bin/volumio5-onboarding" label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.7" (uid=0 pid=3469 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b" label="kernel") Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 20:01:10 rivo volumio[3236]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 20:01:10 rivo volumio5-onboarding[4482]: time=2026-08-27T20:01:10.093+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.25:53224 @ 0x25248a0" latency=25.240426ms timeout=10s endpoint=https://google.com duration=1.921896094s Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0001, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0001/char0002, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0001/char0004, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0006, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0006/char0007, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0006/char0007/desc0009, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000a, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000a/char000b, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000a/char000d, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000f, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000f/char0010, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000f/char0010/desc0012, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service000f/char0010/desc0013, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0014, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0014/char0015, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0014/char0015/desc0017, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0014/char0015/desc0018, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0019, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0019/char001a, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0019/char001a/desc001c, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service001d, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service001d/char001e, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service001d/char001e/desc0020, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service001d/char0021, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char0024, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char0024/desc0026, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char0027, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char0027/desc0029, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char002a, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service0023/char002a/desc002c, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char002e, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char002e/desc0030, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char002e/desc0031, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char0032, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char0032/desc0034, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char0032/desc0035, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char0036, ...) Aug 27 20:01:10 rivo bluealsa[3537]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_70_58_FD_BC_09_38/service002d/char0036/desc0038, ...) Aug 27 20:01:11 rivo volumio[3236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 20:01:11 rivo volumio[3236]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 20:01:11 rivo volumio[3236]: info: MyVolumio login type: Token Aug 27 20:01:11 rivo volumio[3236]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 20:01:11 rivo volumio[3236]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Aug 27 20:01:11 rivo volumio[3236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Aug 27 20:01:11 rivo volumio[3236]: info: BOOT COMPLETED Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 20:01:11 rivo volumio[3236]: info: Not Reporting Auto name since its the default one Aug 27 20:01:11 rivo volumio[3236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 20:01:11 rivo volumio[3236]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 27 20:01:11 rivo volumio[3236]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 27 20:01:11 rivo volumio[3236]: at new NodeError (node:internal/errors:405:5) Aug 27 20:01:11 rivo volumio[3236]: at assert (node:internal/assert:14:11) Aug 27 20:01:11 rivo volumio[3236]: at internalConnectMultiple (node:net:1118:3) Aug 27 20:01:11 rivo volumio[3236]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 27 20:01:11 rivo volumio[3236]: at listOnTimeout (node:internal/timers:575:11) Aug 27 20:01:11 rivo volumio[3236]: at process.processTimers (node:internal/timers:514:7) { Aug 27 20:01:11 rivo volumio[3236]: code: 'ERR_INTERNAL_ASSERTION' Aug 27 20:01:11 rivo volumio[3236]: } Aug 27 20:01:11 rivo volumio[3236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 20:01:12 rivo sudo[4659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 20:00' Aug 27 20:01:12 rivo sudo[4659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="ad2b65e62ee66106fc9799a5d56d019303babfad" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 10:41:31 UTC 2026" VOLUMIO_VERSION="4.185" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="8dbed02e26c8684a8f81563b979083f3"