Aug 28 09:54:00 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Aug 28 09:54:00 rivo volumio[3240]: info: Loading plugin "airplay_emulation"... Aug 28 09:54:00 rivo volumio[3240]: info: Starting Shairport Sync Aug 28 09:54:00 rivo volumio[3240]: info: Loading plugin "cd_controller"... Aug 28 09:54:00 rivo volumio[3240]: info: Loading plugin "last_100"... Aug 28 09:54:00 rivo volumio[3240]: info: Loading plugin "qobuzconnect"... Aug 28 09:54:00 rivo volumio[3240]: info: Loading plugin "raat"... Aug 28 09:54:01 rivo volumio[3240]: info: RAAT Plugin loaded Aug 28 09:54:01 rivo volumio[3240]: info: Adding restartRAATSocket REST API Endpoint Aug 28 09:54:01 rivo volumio[3240]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Aug 28 09:54:01 rivo volumio[3240]: info: Loading plugin "streaming_services"... Aug 28 09:54:00 rivo ntpd[3142]: CLOCK: time stepped by -0.967178 Aug 28 09:54:00 rivo systemd-journald[2588]: Time jumped backwards, rotating. Aug 28 09:54:00 rivo ntpd[3142]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 28 09:54:01 rivo volumio[3240]: info: Loading plugin "tidalconnect"... Aug 28 09:54:01 rivo volumio[3240]: info: Loading plugin "webradio"... Aug 28 09:54:02 rivo volumio[3240]: info: Loading plugin "i2s_dacs"... Aug 28 09:54:02 rivo volumio[3240]: info: I2S DAC not set, start Auto-detection Aug 28 09:54:02 rivo volumio[3240]: info: Loading plugin "volumiodiscovery"... Aug 28 09:54:02 rivo volumio-remote-updater[2976]: [2026-08-28 09:54:02] [connect] Successful connection Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** For more information see Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 09:54:02 rivo volumio[3240]: *** WARNING *** For more information see Aug 28 09:54:02 rivo node[3240]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 28 09:54:02 rivo node[3240]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 09:54:02 rivo node[3240]: *** WARNING *** For more information see Aug 28 09:54:02 rivo node[3240]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 28 09:54:02 rivo node[3240]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 28 09:54:02 rivo node[3240]: *** WARNING *** For more information see Aug 28 09:54:02 rivo volumio[3240]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 28 09:54:02 rivo volumio[3240]: info: Discovery: Started advertising with name: Rivo Aug 28 09:54:02 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 28 09:54:02 rivo volumio[3240]: info: Loading plugin "now_playing"... Aug 28 09:54:04 rivo volumio[3240]: info: Loading plugin "multiroom"... Aug 28 09:54:06 rivo volumio[3240]: info: Applying required configuration parameters for plugin multiroom Aug 28 09:54:06 rivo sudo[3626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 28 09:54:06 rivo sudo[3626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:06 rivo sudo[3626]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:06 rivo volumio[3240]: info: MRS: MultiRoom plugin initialized Aug 28 09:54:06 rivo volumio[3240]: info: MRS: STOPPING SNAPCLIENT Aug 28 09:54:06 rivo volumio[3240]: info: MRS: Snap server stop Aug 28 09:54:06 rivo volumio[3240]: info: MRS: STOPPING volumioStreaming Aug 28 09:54:06 rivo sudo[3642]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 28 09:54:06 rivo sudo[3642]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:06 rivo volumio[3240]: info: Loading plugin "outputs"... Aug 28 09:54:06 rivo sudo[3644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 28 09:54:06 rivo sudo[3644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:06 rivo sudo[3646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 28 09:54:06 rivo volumio[3240]: info: Loading plugin "albumart"... Aug 28 09:54:06 rivo sudo[3646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:06 rivo sudo[3650]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 28 09:54:06 rivo sudo[3650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:06 rivo sudo[3650]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:06 rivo volumio[3240]: info: Plugin example_plugin is not enabled Aug 28 09:54:06 rivo volumio[3240]: info: Loading plugin "hi_res_audio"... Aug 28 09:54:06 rivo sudo[3642]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:06 rivo sudo[3646]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:06 rivo sudo[3644]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:06 rivo volumio[3652]: Forking 3 albumart workers Aug 28 09:54:08 rivo volumio[3661]: Starting albumart workers Aug 28 09:54:08 rivo volumio[3662]: Starting albumart workers Aug 28 09:54:08 rivo volumio[3660]: Starting albumart workers Aug 28 09:54:08 rivo volumio[3240]: info: Applying required configuration parameters for plugin hi_res_audio Aug 28 09:54:08 rivo volumio[3240]: info: Loading plugin "inputs"... Aug 28 09:54:09 rivo volumio[3240]: info: Loading plugin "qobuz"... Aug 28 09:54:11 rivo volumio[3240]: info: Loading plugin "smart_inputs"... Aug 28 09:54:11 rivo volumio[3240]: info: Loading plugin "tidal"... Aug 28 09:54:12 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 28 09:54:12 rivo volumio[3240]: info: Loading plugin "rivocontrol"... Aug 28 09:54:13 rivo volumio[3240]: info: Adding this device properties Aug 28 09:54:13 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 28 09:54:13 rivo volumio[3240]: info: Setting Additional Device Volumio Properties: [object Object] Aug 28 09:54:13 rivo volumio[3240]: info: Loading plugin "updater_comm"... Aug 28 09:54:13 rivo volumio[3240]: info: Loading plugin "cec_controller"... Aug 28 09:54:13 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 28 09:54:13 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 09:54:13 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 09:54:13 rivo volumio[3240]: info: Applying required configuration parameters for plugin cec_controller Aug 28 09:54:13 rivo volumio[3240]: info: Plugin mpdemulation is not enabled Aug 28 09:54:13 rivo volumio[3240]: info: Loading plugin "rest_api"... Aug 28 09:54:13 rivo upmpdcli[3708]: Could not open config: /tmp/upmpdcli.conf Aug 28 09:54:13 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 28 09:54:13 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 28 09:54:13 rivo volumio[3240]: info: Loading plugin "websocket"... Aug 28 09:54:13 rivo volumio[3240]: info: Starting Socket.io Server version 1.7.4 Aug 28 09:54:13 rivo volumio[3240]: info: Loading plugin "minidlna"... Aug 28 09:54:14 rivo volumio[3240]: info: Applying required configuration parameters for plugin minidlna Aug 28 09:54:14 rivo volumio[3240]: info: Loading i18n strings for locale en Aug 28 09:54:14 rivo volumio[3240]: Updating browse sources language Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::initPlayerControls Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:54:14 rivo volumio[3240]: Express server listening on port 3000 Aug 28 09:54:14 rivo volumio[3240]: [Metrics] WebUI: 25s 248.43ms Aug 28 09:54:14 rivo volumio[3240]: info: CoreStateMachine::resetVolumioState Aug 28 09:54:14 rivo volumio[3240]: info: CoreStateMachine::getcurrentVolume Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::volumioRetrievevolume Aug 28 09:54:14 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:14 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:14 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:14 rivo sudo[3739]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:54:14 rivo sudo[3739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:14 rivo volumio[3240]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 28 09:54:14 rivo volumio[3240]: info: Completed loading Core Plugins Aug 28 09:54:14 rivo sudo[3741]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:54:14 rivo sudo[3741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:14 rivo volumio[3240]: info: Preparing to generate the ALSA configuration file Aug 28 09:54:14 rivo sudo[3739]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:14 rivo sudo[3741]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:14 rivo volumio[3240]: info: Volumio Network Manager: Network status updated: 1 Aug 28 09:54:14 rivo volumio[3240]: info: MRS: Removed streaming files Aug 28 09:54:14 rivo volumio[3240]: info: MRS: volumioStreaming STOPPED Aug 28 09:54:14 rivo volumio[3240]: info: MRS: SNAPSERVER STOPPED Aug 28 09:54:14 rivo volumio[3240]: info: MRS: SNAPCLIENT STOPPED Aug 28 09:54:14 rivo volumio[3240]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 28 09:54:14 rivo volumio[3240]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 28 09:54:14 rivo volumio[3240]: info: Reading ALSA contributions from plugins. Aug 28 09:54:14 rivo volumio[3240]: info: Reloading queue from file Aug 28 09:54:15 rivo volumio[3240]: info: CoreStateMachine::setRepeat false single undefined Aug 28 09:54:15 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:15 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:15 rivo volumio[3240]: info: CoreStateMachine::setRandom null Aug 28 09:54:15 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:15 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:15 rivo volumio[3240]: info: Asound.conf file unchanged, so no further update is needed Aug 28 09:54:15 rivo volumio[3240]: info: Output device has changed, restarting MPD Aug 28 09:54:15 rivo sudo[3753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 28 09:54:15 rivo sudo[3753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo volumio[3240]: info: Output device has changed, restarting Shairport Sync Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:15 rivo sudo[3756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 09:54:15 rivo sudo[3756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo sudo[3756]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:15 rivo sudo[3759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 09:54:15 rivo sudo[3759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo volumio[3240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 09:54:15 rivo volumio[3240]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:15 rivo volumio[3240]: info: ___________ START PLUGINS ___________ Aug 28 09:54:15 rivo volumio[3240]: info: ControllerMpd::onStart: Initializing MPD Aug 28 09:54:15 rivo volumio[3240]: info: Creating MPD Configuration file Aug 28 09:54:15 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 09:54:15 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:54:15 rivo sudo[3768]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 09:54:15 rivo volumio[3240]: info: [1787903655279] CoreMusicLibrary::Adding element Media Servers Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:15 rivo sudo[3768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo volumio[3240]: info: UPNP Browser: Client initialized successfully Aug 28 09:54:15 rivo sudo[3771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 28 09:54:15 rivo sudo[3771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo sudo[3771]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:15 rivo volumio[3240]: info: Adding METAVOLUMIO REST API Endpoints Aug 28 09:54:15 rivo volumio[3240]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 28 09:54:15 rivo volumio[3240]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 28 09:54:15 rivo volumio[3240]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 28 09:54:15 rivo volumio[3240]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 28 09:54:15 rivo sudo[3775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 09:54:15 rivo sudo[3775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:15 rivo volumio[3240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:15 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 09:54:15 rivo sudo[3768]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:15 rivo volumio[3240]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 28 09:54:15 rivo volumio[3240]: info: Preparing CD Folders Aug 28 09:54:15 rivo volumio[3240]: info: Adding CD REST API Endpoints Aug 28 09:54:15 rivo volumio[3240]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 28 09:54:15 rivo volumio[3240]: info: Starting UDEV Watcher for CD Aug 28 09:54:15 rivo volumio[3240]: info: Detecting CD presence with UDEV Aug 28 09:54:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 28 09:54:15 rivo systemd[1]: mpd.service: Deactivated successfully. Aug 28 09:54:15 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 28 09:54:15 rivo systemd[1]: mpd.socket: Deactivated successfully. Aug 28 09:54:15 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 28 09:54:15 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 28 09:54:15 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 28 09:54:15 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 28 09:54:15 rivo volumio5-onboarding[3784]: time=2026-08-28T09:54:15.833+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 28 09:54:15 rivo sudo[3788]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 09:54:15 rivo sudo[3788]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 28 09:54:15 rivo sudo[3797]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 28 09:54:15 rivo sudo[3788]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:17 rivo volumio-remote-updater[2976]: [2026-08-28 09:54:17] [connect] Successful connection Aug 28 09:54:20 rivo mpd[3798]: 2026-08-28T09:54:20 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 09:54:20 rivo volumio[3240]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 09:54:20 rivo volumio[3240]: info: [1787903660492] CoreMusicLibrary::Adding element Last_100 Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:20 rivo volumio[3240]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 28 09:54:20 rivo volumio[3240]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 28 09:54:20 rivo volumio[3240]: info: Starting RAAT Plugin Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 28 09:54:20 rivo volumio[3240]: info: Additional UI Settings Added for plugin music_service/raat Aug 28 09:54:20 rivo volumio[3240]: info: Registering DSP Elements listener and retrieving current ones Aug 28 09:54:20 rivo volumio[3240]: info: Additional DSP elements updated Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:20 rivo volumio[3240]: info: Updating RAAT Signal Path Aug 28 09:54:20 rivo volumio[3240]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 28 09:54:20 rivo volumio[3240]: info: Streaming services startup Aug 28 09:54:20 rivo volumio[3240]: info: Starting Streaming Daemon Aug 28 09:54:20 rivo sudo[3805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 28 09:54:20 rivo sudo[3805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:20 rivo sudo[3810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 09:54:20 rivo sudo[3810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:20 rivo sudo[3805]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 09:54:20 rivo volumio[3240]: info: [1787903660631] CoreMusicLibrary::Adding element Webradio Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:20 rivo sudo[3814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 28 09:54:20 rivo sudo[3814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 09:54:20 rivo volumio[3240]: info: Initializing BBC Radios Aug 28 09:54:20 rivo sudo[3810]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:20 rivo sudo[3818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 28 09:54:20 rivo sudo[3818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:20 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 28 09:54:20 rivo sudo[3814]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:20 rivo volumio[3240]: info: [now-playing] ConfigUpdater: config is up to date. Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 28 09:54:20 rivo volumio[3240]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:20 rivo sudo[3818]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:20 rivo volumio[3240]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 28 09:54:20 rivo volumio[3240]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 28 09:54:20 rivo volumio[3240]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 28 09:54:20 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 28 09:54:20 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 28 09:54:20 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 28 09:54:20 rivo volumio[3240]: info: Touch Event Listener Process Starting Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 09:54:20 rivo volumio[3240]: info: [1787903660887] CoreMusicLibrary::Adding element QOBUZ Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:20 rivo volumio[3240]: Cannot find translation for source QOBUZ Aug 28 09:54:20 rivo volumio[3240]: info: Refreshing QOBUZ token Aug 28 09:54:20 rivo sudo[3841]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 28 09:54:20 rivo sudo[3841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:20 rivo volumio[3240]: info: Adding inputs REST Endpoints Aug 28 09:54:20 rivo volumio[3240]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 28 09:54:20 rivo volumio[3240]: info: Scanning Audio Inputs Aug 28 09:54:20 rivo volumio[3240]: info: Checking against Known Cards name Aug 28 09:54:20 rivo volumio[3240]: info: Checking against Known Cards name Aug 28 09:54:20 rivo volumio[3240]: info: Checking against Known Cards name Aug 28 09:54:20 rivo volumio[3240]: info: Checking against Known Cards name Aug 28 09:54:20 rivo volumio[3240]: info: Checking against Known Cards name Aug 28 09:54:20 rivo volumio[3240]: info: Adding Server instance for streaming Aug 28 09:54:20 rivo volumio[3240]: info: Adding TIDAL to Browse Sources Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 28 09:54:20 rivo volumio[3240]: info: [1787903660964] CoreMusicLibrary::Adding element TIDAL Aug 28 09:54:20 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:20 rivo volumio[3240]: Cannot find translation for source QOBUZ Aug 28 09:54:20 rivo volumio[3240]: Cannot find translation for source TIDAL Aug 28 09:54:20 rivo volumio[3240]: info: Refreshing TIDAL token Aug 28 09:54:21 rivo sudo[3852]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 28 09:54:21 rivo sudo[3852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3852]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo sudo[3855]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 28 09:54:21 rivo sudo[3855]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3855]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo sudo[3858]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 28 09:54:21 rivo sudo[3858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3858]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo sudo[3861]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 28 09:54:21 rivo sudo[3861]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3861]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo volumio[3240]: info: Apply VIM3L Onboard LEDs Settings Aug 28 09:54:21 rivo volumio[3240]: info: Turning On Onboard LEDs Aug 28 09:54:21 rivo volumio[3240]: info: Starting CEC Event listener Aug 28 09:54:21 rivo sudo[3866]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 28 09:54:21 rivo volumio[3240]: info: Loading i18n strings for locale en Aug 28 09:54:21 rivo sudo[3866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3866]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo volumio[3240]: info: Volumio Calling Home Aug 28 09:54:21 rivo sudo[3870]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 28 09:54:21 rivo sudo[3870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:21 rivo sudo[3870]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo volumio[3240]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 28 09:54:21 rivo systemd[1]: Started mpd.service - Music Player Daemon. Aug 28 09:54:21 rivo volumio[3240]: (node:3240) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Aug 28 09:54:21 rivo volumio[3240]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 28 09:54:21 rivo sudo[3759]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo sudo[3775]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:21 rivo volumio[3240]: info: [now-playing] App is listening on port 4004. Aug 28 09:54:21 rivo volumio[3240]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 28 09:54:21 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 28 09:54:21 rivo volumio[3240]: info: Serial port opened successfully Aug 28 09:54:21 rivo volumio[3240]: info: Sending serial start messages Aug 28 09:54:21 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:21 rivo volumio[3240]: info: Reporting MCU Network Status: 1 Aug 28 09:54:21 rivo volumio[3240]: info: MP1 GPIO: Signalled system ready via GPIO Aug 28 09:54:21 rivo volumio[3240]: error: Cannot start Volumio Streaming Daemon Aug 28 09:54:21 rivo volumio[3240]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 09:54:21 rivo volumio[3240]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 09:54:21 rivo volumio[3240]: info: RAAT Albumart path created successfully Aug 28 09:54:21 rivo volumio[3240]: info: MPD Permissions set Aug 28 09:54:21 rivo volumio[3240]: info: MPD Permissions set Aug 28 09:54:21 rivo volumio[3240]: info: Volumio called home Aug 28 09:54:21 rivo volumio[3240]: info: minidlna: Found miniDLNA version 1.3.0 Aug 28 09:54:21 rivo volumio[3240]: info: minidlna: Starting minidlna.service Aug 28 09:54:21 rivo sudo[3886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 28 09:54:21 rivo sudo[3886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Aug 28 09:54:22 rivo sudo[3886]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo volumio[3240]: error: MPD error: The expression evaluated to a falsy value: Aug 28 09:54:22 rivo volumio[3240]: assert.ok(self.idling) Aug 28 09:54:22 rivo volumio[3240]: error: The expression evaluated to a falsy value: Aug 28 09:54:22 rivo volumio[3240]: assert.ok(self.idling) Aug 28 09:54:22 rivo volumio[3240]: info: MPD running with PID3798 Aug 28 09:54:22 rivo volumio[3240]: ,establishing connection Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 28 09:54:22 rivo volumio[3240]: info: Setting Device Volume Override Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 28 09:54:22 rivo volumio[3240]: info: Updating Volume Controller Parameters: Device: 5 Name: X-SABRE Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 28 09:54:22 rivo volumio[3240]: info: Disabling external Volume Control Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 28 09:54:22 rivo volumio[3240]: info: Setting Additional System Software info: Hardware Revision: 2.0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 28 09:54:22 rivo volumio[3240]: info: Setting HW Firmware info: undefined Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 28 09:54:22 rivo volumio[3240]: info: Setting HW Version info: 2.0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 28 09:54:22 rivo volumio[3240]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.5 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 28 09:54:22 rivo volumio[3240]: info: Setting HW Firmware info: 0.3.5 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 28 09:54:22 rivo volumio[3240]: info: Setting HW Version info: 2.0 Aug 28 09:54:22 rivo volumio[3240]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 28 09:54:22 rivo volumio[3240]: info: MCU Signalled Headphone Mode Disabled Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 28 09:54:22 rivo volumio[3240]: info: Reporting Headphone State: false Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:54:22 rivo volumio[3240]: info: Updating RAAT Signal Path Aug 28 09:54:22 rivo volumio[3240]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 28 09:54:22 rivo volumio[3240]: info: MCU Signalled Sleep Mode Disabled Aug 28 09:54:22 rivo volumio[3240]: info: Enabling Advanced system settings configuration Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 28 09:54:22 rivo volumio[3240]: info: Additional UI Settings Added for plugin music_service/inputs Aug 28 09:54:22 rivo volumio[3240]: info: MCU Signalled Auto Boot Mode On Power Disabled Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo sudo[3904]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 28 09:54:22 rivo sudo[3904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo volumio[3240]: error: updateQueue error: null Aug 28 09:54:22 rivo volumio[3240]: info: minidlna: systemctl start minidlna.service succeeded. Aug 28 09:54:22 rivo sudo[3904]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 28 09:54:22 rivo volumio[3240]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Aug 28 09:54:22 rivo volumio[3240]: error: updateQueue error: null Aug 28 09:54:22 rivo volumio[3240]: 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: 1 Aug 28 09:54:22 rivo volumio[3240]: info: Starting Shairport Sync Aug 28 09:54:22 rivo volumio[3240]: info: Starting Shairport Sync Aug 28 09:54:22 rivo volumio[3240]: info: Starting Shairport Sync Aug 28 09:54:22 rivo sudo[3913]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 28 09:54:22 rivo sudo[3913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo sudo[3914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 09:54:22 rivo sudo[3914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo sudo[3913]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo volumio[3240]: 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: 1 Aug 28 09:54:22 rivo volumio[3240]: info: Received Get System Info Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:54:22 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo sudo[3917]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 09:54:22 rivo sudo[3917]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:54:22 rivo volumio5-onboarding[3784]: time=2026-08-28T09:54:22.510+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 28 09:54:22 rivo sudo[3921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 28 09:54:22 rivo sudo[3921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:22 rivo volumio5-onboarding[3784]: time=2026-08-28T09:54:22.524+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 28 09:54:22 rivo volumio[3240]: info: Adding Inputs via Serial API Aug 28 09:54:22 rivo volumio[3240]: info: Received Get System Info Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:54:22 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:54:22 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 28 09:54:22 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 28 09:54:22 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 09:54:22 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 28 09:54:22 rivo sudo[3921]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo sudo[3914]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo sudo[3917]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:22 rivo volumio[3240]: info: Executing endpoint qc_getconfig Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.670 [3831.3831] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 28 09:54:22 rivo volumio[3240]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 28 09:54:22 rivo volumio[3240]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 28 09:54:22 rivo volumio[3240]: info: Shairport-Sync Started Aug 28 09:54:22 rivo volumio[3240]: Error adding Membership: Error: addMembership EINVAL Aug 28 09:54:22 rivo volumio[3240]: info: Shairport-Sync Started Aug 28 09:54:22 rivo volumio[3240]: info: Shairport-Sync Started Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::servicePushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} Aug 28 09:54:22 rivo volumio[3240]: verbose: CURRENT POSITION 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::syncState stateService stop Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: No code Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:22 rivo volumio[3240]: Cannot find translation for source QOBUZ Aug 28 09:54:22 rivo volumio[3240]: Cannot find translation for source TIDAL Aug 28 09:54:22 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.850 [3831.3831] INFO VolumeManager: [0xac5b4f68]: Setting new playback volume: 75 Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.850 [3831.3831] INFO VolumeManager: [0xac5b4f68]: Setting new mute state: 0 Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.850 [3831.3831] INFO AudioStreamManager: [0xac5b4cc0]: Setting new audio download buffer size: 1048576 Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.850 [3831.3831] INFO QobuzConnect: [0xac5b5830]: Client initialized! Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.850 [3831.3831] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.875 [3831.3831] INFO LocalConfigManager: [0xac5b49e8]: Starting Local Configuration server Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.875 [3831.3831] INFO SampleApp: Starting Local configuration server Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.876 [3831.3831] INFO SampleApp: Connected to UNIX socket client 0xac59f908 Aug 28 09:54:22 rivo qobuz-connect[3831]: 20260828 09:54:22.876 [3831.3831] INFO SampleApp: Playback volume changed: 75 Aug 28 09:54:22 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:22 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:23 rivo volumio[3240]: info: TidalConnect service stoped! Aug 28 09:54:23 rivo volumio[3240]: info: Turning Off Onboard LEDs Aug 28 09:54:23 rivo volumio[3240]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 28 09:54:23 rivo volumio[3240]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 28 09:54:23 rivo sudo[3976]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 28 09:54:23 rivo sudo[3976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:23 rivo sudo[3976]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:23 rivo sudo[3978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 28 09:54:23 rivo sudo[3978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:23 rivo sudo[3981]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 28 09:54:23 rivo sudo[3981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:23 rivo sudo[3981]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:23 rivo volumio[3240]: info: Onboard LEDs initialized Aug 28 09:54:23 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 28 09:54:24 rivo sudo[3978]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:24 rivo volumio[3240]: info: Executing endpoint tc_getconfig Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 28 09:54:24 rivo vtcs[3987]: STARTING TidalConnect services, version: 1.6.1 Aug 28 09:54:24 rivo vtcs[3987]: STARTED TidalConnect services. Aug 28 09:54:24 rivo volumio[3240]: info: Executing endpoint tc_connect Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 28 09:54:24 rivo volumio[3240]: info: Connecting to TidalConnect Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::servicePushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 28 09:54:24 rivo volumio[3240]: verbose: CURRENT POSITION 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::syncState stateService stop Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: No code Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::servicePushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Aug 28 09:54:24 rivo volumio[3240]: verbose: CURRENT POSITION 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::syncState stateService stop Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::syncState currentStatus stop Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: No code Aug 28 09:54:24 rivo volumio[3240]: info: CoreStateMachine::pushState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioPushState Aug 28 09:54:24 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:24 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:25 rivo volumio[3240]: info: MRS: Getting audio outputs on start Aug 28 09:54:25 rivo volumio[3240]: info: MRS: Requesting all other devices output Aug 28 09:54:26 rivo sudo[4021]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:54:26 rivo sudo[4021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:26 rivo sudo[4023]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:54:26 rivo sudo[4023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:26 rivo sudo[4021]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:26 rivo sudo[4023]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:26 rivo volumio[3240]: 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: 2 Aug 28 09:54:27 rivo volumio[3240]: info: TidalConnect service started! Aug 28 09:54:27 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 28 09:54:27 rivo kernel: fb: mem_free_work, free memory: addr:800000 Aug 28 09:54:28 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::volumioGetVisibleSources Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:28 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 28 09:54:28 rivo volumio[3240]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 28 09:54:28 rivo volumio[3240]: info: Received Get System Info Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:54:28 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:28 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:28 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:28 rivo volumio[3240]: info: Listing playlists Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 28 09:54:28 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 09:54:28 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 28 09:54:28 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 28 09:54:28 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 28 09:54:28 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 09:54:28 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 28 09:54:28 rivo sudo[3753]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:28 rivo volumio[3240]: info: Upmpdcli Daemon Started Aug 28 09:54:29 rivo sudo[4093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 28 09:54:29 rivo sudo[4095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 28 09:54:29 rivo sudo[4095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:29 rivo sudo[4093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 28 09:54:29 rivo sudo[4095]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:29 rivo sudo[4093]: pam_unix(sudo:session): session closed for user root Aug 28 09:54:30 rivo volumio[3240]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 28 09:54:31 rivo volumio[3240]: info: Checking for updated MCU Firmware Aug 28 09:54:31 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 09:54:31 rivo volumio[3240]: info: Firware on device is on latest version, no need to update Aug 28 09:54:31 rivo volumio[3240]: 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: 2 Aug 28 09:54:32 rivo volumio-remote-updater[2976]: [2026-08-28 09:54:32] [connect] Successful connection Aug 28 09:54:35 rivo volumio-remote-updater[2976]: [2026-08-28 09:54:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787903672 101 Aug 28 09:54:35 rivo volumio[3240]: 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: 3 Aug 28 09:54:43 rivo volumio[3240]: info: Discovery: adding 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 28 09:54:43 rivo volumio[3240]: info: Discovery: Found device Rivo Aug 28 09:54:43 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:43 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:43 rivo volumio[3240]: info: MRS: Pushing multiroomSync output for this device Aug 28 09:54:43 rivo volumio[3240]: info: MRS: Pushing multiroomSync output Aug 28 09:54:43 rivo volumio[3240]: info: Adding audio output: Aug 28 09:54:43 rivo volumio[3240]: info: Adding audio output: Aug 28 09:54:43 rivo volumio[3240]: info: Discovery: this is already registered, 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 28 09:54:43 rivo volumio[3240]: info: Discovery: Found device Rivo Aug 28 09:54:43 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:43 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:48 rivo volumio[3240]: info: Access Token successfully retrieved Aug 28 09:54:48 rivo volumio[3240]: info: Stopping AccessToken refresher cron for QOBUZ Aug 28 09:54:48 rivo volumio[3240]: info: AccessToken refresher cron started for QOBUZ Aug 28 09:54:48 rivo volumio[3240]: info: Adding QOBUZ REST API Endpoints Aug 28 09:54:49 rivo volumio[3240]: info: Access Token successfully retrieved Aug 28 09:54:49 rivo volumio[3240]: error: Hi Res Audio Failed Login: [object Object] Aug 28 09:54:51 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 09:54:51 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:54:51 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 09:54:51 rivo volumio[3240]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Aug 28 09:54:51 rivo volumio[3240]: info: ------------------------------------------- Aug 28 09:54:51 rivo volumio[3240]: info: ----- MyVolumio plugins startup ---- Aug 28 09:54:51 rivo volumio[3240]: info: ------------------------------------------- Aug 28 09:54:51 rivo volumio[3240]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 28 09:54:52 rivo volumio5-onboarding[3784]: failed to bootstrap state: failed to check for software update: could not check for updates: context deadline exceeded Aug 28 09:54:52 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Aug 28 09:54:52 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Aug 28 09:54:52 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Aug 28 09:54:52 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 09:54:52 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 28 09:54:52 rivo volumio5-onboarding[4432]: time=2026-08-28T09:54:52.908+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 28 09:54:53 rivo volumio[3240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 28 09:54:55 rivo volumio[3240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 28 09:54:57 rivo volumio[3240]: 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: 5 Aug 28 09:54:59 rivo volumio[3240]: 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: 5 Aug 28 09:54:59 rivo volumio[3240]: info: Received Get System Info Aug 28 09:54:59 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:54:59 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:54:59 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:54:59 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:54:59 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:54:59 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:54:59 rivo volumio5-onboarding[4432]: time=2026-08-28T09:54:59.044+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 28 09:54:59 rivo volumio5-onboarding[4432]: time=2026-08-28T09:54:59.068+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 28 09:55:02 rivo volumio[3240]: info: Received Get System Info Aug 28 09:55:02 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:55:02 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:55:02 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:55:02 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:55:02 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:55:02 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:55:04 rivo volumio-remote-updater[2976]: Test mode enabled Aug 28 09:55:04 rivo volumio-remote-updater[2976]: Alpha mode disabled Aug 28 09:55:04 rivo volumio-remote-updater[2976]: Alpha legacy test mode disabled Aug 28 09:55:08 rivo volumio[3240]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 09:55:08 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 09:55:10 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 09:55:12 rivo volumio[3240]: info: MRS: Found cast device: CHSTB02-2c07cb952969f3ad2bbb27b23c87610d Aug 28 09:55:12 rivo volumio[3240]: info: Adding audio output: Aug 28 09:55:12 rivo volumio[3240]: info: MRS: Found cast device: CHSTB02-88837a1d9d2011fd891ba3df03e82fab Aug 28 09:55:12 rivo volumio[3240]: info: Adding audio output: Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 09:55:12 rivo volumio[3240]: error: MyVolumio Plugin failed to start in a timely fashion Aug 28 09:55:12 rivo volumio[3240]: [Metrics] CommandRouter: 81s 999.87ms Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::Close All Modals sent Aug 28 09:55:12 rivo volumio[3240]: info: CoreCommandRouter::Close All Modals sent Aug 28 09:55:13 rivo upmpdcli[4506]: writing RSA key Aug 28 09:55:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 28 09:55:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 28 09:55:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 09:55:15 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 28 09:55:17 rivo volumio[3240]: info: Received Get System Info Aug 28 09:55:17 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 09:55:17 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 09:55:17 rivo volumio[3240]: info: Discovery: Getting this device information Aug 28 09:55:17 rivo volumio[3240]: info: CoreCommandRouter::volumioGetState Aug 28 09:55:17 rivo volumio[3240]: info: CorePlayQueue::getTrack 0 Aug 28 09:55:17 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 09:55:19 rivo volumio5-onboarding[4432]: time=2026-08-28T09:55:19.441+02:00 level=INFO msg="enabling local network discovery" Aug 28 09:55:19 rivo volumio5-onboarding[4432]: time=2026-08-28T09:55:19.445+02:00 level=INFO msg="enabling BLE discovery" Aug 28 09:55:19 rivo volumio[3240]: info: BOOT COMPLETED Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 28 09:55:19 rivo volumio[3240]: info: Not Reporting Auto name since its the default one Aug 28 09:55:20 rivo volumio5-onboarding[4432]: time=2026-08-28T09:55:20.462+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 09:55:21 rivo volumio[3240]: info: Successfully retrieved User Session From TIDAL Aug 28 09:55:21 rivo volumio[3240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 28 09:55:31 rivo volumio[3240]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 28 09:55:32 rivo volumio[3240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 28 09:55:32 rivo volumio[3240]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 28 09:55:32 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:55:32 rivo volumio[3240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 09:55:32 rivo volumio[3240]: info: Starting MyVolumio Remote Streaming Endpoints Aug 28 09:55:32 rivo volumio[3240]: info: MyVolumio login type: Token Aug 28 09:55:32 rivo volumio[3240]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 28 09:55:32 rivo volumio[3240]: 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 28 09:55:32 rivo volumio[3240]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 28 09:55:32 rivo volumio[3240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:55:32 rivo volumio[3240]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 28 09:55:32 rivo volumio[3240]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 28 09:55:32 rivo volumio[3240]: at new NodeError (node:internal/errors:405:5) Aug 28 09:55:32 rivo volumio[3240]: at assert (node:internal/assert:14:11) Aug 28 09:55:32 rivo volumio[3240]: at internalConnectMultiple (node:net:1118:3) Aug 28 09:55:32 rivo volumio[3240]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 28 09:55:32 rivo volumio[3240]: at listOnTimeout (node:internal/timers:575:11) Aug 28 09:55:32 rivo volumio[3240]: at process.processTimers (node:internal/timers:514:7) { Aug 28 09:55:32 rivo volumio[3240]: code: 'ERR_INTERNAL_ASSERTION' Aug 28 09:55:32 rivo volumio[3240]: } Aug 28 09:55:32 rivo volumio[3240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:55:33 rivo sudo[4590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-28 09:54' Aug 28 09:55:33 rivo sudo[4590]: 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"