Aug 27 19:22:01 rivo volumio[3231]: info: Applying required configuration parameters for plugin multiroom Aug 27 19:22:01 rivo sudo[3590]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 27 19:22:01 rivo sudo[3590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:01 rivo sudo[3590]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:01 rivo volumio[3231]: info: MRS: MultiRoom plugin initialized Aug 27 19:22:01 rivo volumio[3231]: info: MRS: STOPPING SNAPCLIENT Aug 27 19:22:01 rivo volumio[3231]: info: MRS: Snap server stop Aug 27 19:22:01 rivo volumio[3231]: info: MRS: STOPPING volumioStreaming Aug 27 19:22:01 rivo sudo[3607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 27 19:22:01 rivo sudo[3607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:01 rivo sudo[3609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 27 19:22:01 rivo sudo[3609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:01 rivo volumio[3231]: info: Loading plugin "outputs"... Aug 27 19:22:01 rivo sudo[3611]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 27 19:22:01 rivo sudo[3611]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:01 rivo volumio[3231]: info: Loading plugin "albumart"... Aug 27 19:22:01 rivo volumio[3231]: info: Plugin example_plugin is not enabled Aug 27 19:22:01 rivo volumio[3231]: info: Loading plugin "hi_res_audio"... Aug 27 19:22:01 rivo sudo[3615]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 27 19:22:01 rivo sudo[3615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:01 rivo sudo[3615]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:01 rivo sudo[3607]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:01 rivo sudo[3609]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:01 rivo sudo[3611]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:02 rivo volumio[3617]: Forking 3 albumart workers Aug 27 19:22:03 rivo volumio[3231]: info: Applying required configuration parameters for plugin hi_res_audio Aug 27 19:22:03 rivo volumio[3231]: info: Loading plugin "inputs"... Aug 27 19:22:03 rivo volumio[3625]: Starting albumart workers Aug 27 19:22:03 rivo volumio[3627]: Starting albumart workers Aug 27 19:22:03 rivo volumio[3626]: Starting albumart workers Aug 27 19:22:04 rivo volumio[3231]: info: Loading plugin "qobuz"... Aug 27 19:22:06 rivo volumio-remote-updater[2951]: [2026-08-27 19:22:06] [connect] Successful connection Aug 27 19:22:06 rivo volumio[3231]: info: Loading plugin "smart_inputs"... Aug 27 19:22:06 rivo volumio[3231]: info: Loading plugin "tidal"... Aug 27 19:22:07 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 27 19:22:07 rivo volumio[3231]: info: Loading plugin "rivocontrol"... Aug 27 19:22:08 rivo volumio[3231]: info: Adding this device properties Aug 27 19:22:08 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Aug 27 19:22:08 rivo volumio[3231]: info: Setting Additional Device Volumio Properties: [object Object] Aug 27 19:22:08 rivo volumio[3231]: info: Loading plugin "updater_comm"... Aug 27 19:22:08 rivo volumio[3231]: info: Loading plugin "cec_controller"... Aug 27 19:22:08 rivo volumio[3231]: info: Applying required configuration parameters for plugin cec_controller Aug 27 19:22:08 rivo volumio[3231]: info: Plugin mpdemulation is not enabled Aug 27 19:22:08 rivo volumio[3231]: info: Loading plugin "rest_api"... Aug 27 19:22:08 rivo volumio[3231]: info: Loading plugin "websocket"... Aug 27 19:22:08 rivo volumio[3231]: info: Starting Socket.io Server version 1.7.4 Aug 27 19:22:08 rivo volumio[3231]: info: Loading plugin "minidlna"... Aug 27 19:22:09 rivo volumio[3231]: info: Applying required configuration parameters for plugin minidlna Aug 27 19:22:09 rivo volumio[3231]: info: Loading i18n strings for locale en Aug 27 19:22:09 rivo volumio[3231]: Updating browse sources language Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:09 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 27 19:22:09 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 19:22:09 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 19:22:09 rivo upmpdcli[3673]: Could not open config: /tmp/upmpdcli.conf Aug 27 19:22:09 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 19:22:09 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::initPlayerControls Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 19:22:09 rivo volumio[3231]: Express server listening on port 3000 Aug 27 19:22:09 rivo volumio[3231]: [Metrics] WebUI: 24s 881.25ms Aug 27 19:22:09 rivo volumio[3231]: info: CoreStateMachine::resetVolumioState Aug 27 19:22:09 rivo volumio[3231]: info: CoreStateMachine::getcurrentVolume Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 19:22:09 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:09 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:09 rivo sudo[3704]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 19:22:09 rivo sudo[3704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:09 rivo volumio[3231]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 27 19:22:09 rivo volumio[3231]: info: Completed loading Core Plugins Aug 27 19:22:09 rivo volumio[3231]: info: Preparing to generate the ALSA configuration file Aug 27 19:22:09 rivo sudo[3706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 19:22:09 rivo sudo[3706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:09 rivo sudo[3704]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:09 rivo sudo[3706]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:09 rivo volumio[3231]: info: Volumio Network Manager: Network status updated: 0 Aug 27 19:22:09 rivo volumio[3231]: info: MRS: Removed streaming files Aug 27 19:22:09 rivo volumio[3231]: info: MRS: volumioStreaming STOPPED Aug 27 19:22:09 rivo volumio[3231]: info: MRS: SNAPSERVER STOPPED Aug 27 19:22:09 rivo volumio[3231]: info: MRS: SNAPCLIENT STOPPED Aug 27 19:22:09 rivo volumio[3231]: info: Cannot mount NAS MJUZA_2 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 19:22:09 rivo volumio[3231]: info: Cannot mount NAS MJUZA_1 at system boot, trial number 1 ,retrying in 5 seconds Aug 27 19:22:09 rivo volumio[3231]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Aug 27 19:22:09 rivo volumio[3231]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 19:22:09 rivo volumio[3231]: info: Reading ALSA contributions from plugins. Aug 27 19:22:10 rivo volumio[3231]: info: Reloading queue from file Aug 27 19:22:10 rivo volumio[3231]: info: CoreStateMachine::setRepeat false single undefined Aug 27 19:22:10 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:10 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:10 rivo volumio[3231]: info: CoreStateMachine::setRandom null Aug 27 19:22:10 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:10 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:10 rivo volumio-remote-updater[2951]: [2026-08-27 19:22:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787851326 101 Aug 27 19:22:10 rivo sudo[3718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 27 19:22:10 rivo sudo[3718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo volumio[3231]: 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: 1 Aug 27 19:22:10 rivo volumio[3231]: info: Asound.conf file unchanged, so no further update is needed Aug 27 19:22:10 rivo volumio[3231]: info: Output device has changed, restarting MPD Aug 27 19:22:10 rivo volumio[3231]: info: Output device has changed, restarting Shairport Sync Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:10 rivo sudo[3721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 19:22:10 rivo sudo[3721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo sudo[3723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 19:22:10 rivo sudo[3721]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:10 rivo sudo[3723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo volumio[3231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 19:22:10 rivo volumio[3231]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: ___________ START PLUGINS ___________ Aug 27 19:22:10 rivo volumio[3231]: info: ControllerMpd::onStart: Initializing MPD Aug 27 19:22:10 rivo volumio[3231]: info: Creating MPD Configuration file Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 19:22:10 rivo volumio[3231]: info: [1787851330329] CoreMusicLibrary::Adding element Media Servers Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:10 rivo sudo[3732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 19:22:10 rivo sudo[3732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo volumio[3231]: info: UPNP Browser: Client initialized successfully Aug 27 19:22:10 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 19:22:10 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 19:22:10 rivo sudo[3734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 19:22:10 rivo sudo[3734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo sudo[3737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 19:22:10 rivo sudo[3737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:10 rivo sudo[3734]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:10 rivo volumio[3231]: info: Adding METAVOLUMIO REST API Endpoints Aug 27 19:22:10 rivo volumio[3231]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 27 19:22:10 rivo volumio[3231]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 27 19:22:10 rivo volumio[3231]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 27 19:22:10 rivo volumio[3231]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:10 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 27 19:22:10 rivo volumio[3231]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 27 19:22:10 rivo sudo[3732]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:10 rivo volumio[3231]: info: Preparing CD Folders Aug 27 19:22:10 rivo volumio[3231]: info: Adding CD REST API Endpoints Aug 27 19:22:10 rivo volumio[3231]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 27 19:22:10 rivo volumio[3231]: info: Starting UDEV Watcher for CD Aug 27 19:22:10 rivo volumio[3231]: info: Detecting CD presence with UDEV Aug 27 19:22:10 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 27 19:22:10 rivo systemd[1]: mpd.service: Deactivated successfully. Aug 27 19:22:10 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 19:22:10 rivo systemd[1]: mpd.socket: Deactivated successfully. Aug 27 19:22:10 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 19:22:10 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 19:22:10 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 19:22:10 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 19:22:10 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:10.908+02:00 level=INFO msg="running volumio4-device-gateway" version=2a6bbeeb+CHANGES buildDate=2026-07-03T07:51:28Z Aug 27 19:22:10 rivo sudo[3752]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 19:22:10 rivo sudo[3752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 19:22:10 rivo sudo[3762]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 19:22:10 rivo sudo[3752]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:15 rivo mpd[3763]: 2026-08-27T19:22:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 19:22:15 rivo volumio[3231]: warn: [cd-plugin] cdspeedctl: device or media not ready Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 19:22:15 rivo volumio[3231]: info: [1787851335569] CoreMusicLibrary::Adding element Last_100 Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:15 rivo volumio[3231]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 27 19:22:15 rivo volumio[3231]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 27 19:22:15 rivo volumio[3231]: info: Starting RAAT Plugin Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Aug 27 19:22:15 rivo volumio[3231]: info: Additional UI Settings Added for plugin music_service/raat Aug 27 19:22:15 rivo volumio[3231]: info: Registering DSP Elements listener and retrieving current ones Aug 27 19:22:15 rivo volumio[3231]: info: Additional DSP elements updated Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:15 rivo volumio[3231]: info: Updating RAAT Signal Path Aug 27 19:22:15 rivo volumio[3231]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 19:22:15 rivo sudo[3770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 27 19:22:15 rivo sudo[3770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:15 rivo volumio[3231]: info: Streaming services startup Aug 27 19:22:15 rivo volumio[3231]: info: Starting Streaming Daemon Aug 27 19:22:15 rivo sudo[3770]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:15 rivo sudo[3775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 19:22:15 rivo sudo[3775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 19:22:15 rivo volumio[3231]: info: [1787851335685] CoreMusicLibrary::Adding element Webradio Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 19:22:15 rivo volumio[3231]: info: Initializing BBC Radios Aug 27 19:22:15 rivo sudo[3781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 27 19:22:15 rivo sudo[3781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:15 rivo sudo[3775]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:15 rivo sudo[3783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 27 19:22:15 rivo sudo[3783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:15 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 27 19:22:15 rivo sudo[3781]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:15 rivo volumio[3231]: info: [now-playing] ConfigUpdater: config is up to date. Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 27 19:22:15 rivo volumio[3231]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:15 rivo sudo[3783]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:15 rivo volumio[3231]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 27 19:22:15 rivo volumio[3231]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 27 19:22:15 rivo volumio[3231]: info: Initializing Serial Communication on port /dev/ttyS3 Aug 27 19:22:15 rivo volumio[3231]: info: Touch Event Listener Process Starting Aug 27 19:22:15 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Aug 27 19:22:15 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Aug 27 19:22:15 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 19:22:15 rivo volumio[3231]: info: [1787851335966] CoreMusicLibrary::Adding element QOBUZ Aug 27 19:22:15 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:15 rivo volumio[3231]: Cannot find translation for source QOBUZ Aug 27 19:22:15 rivo volumio[3231]: info: Refreshing QOBUZ token Aug 27 19:22:15 rivo sudo[3807]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Aug 27 19:22:15 rivo sudo[3807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:15 rivo volumio[3231]: info: Adding inputs REST Endpoints Aug 27 19:22:15 rivo volumio[3231]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 27 19:22:15 rivo volumio[3231]: info: Scanning Audio Inputs Aug 27 19:22:16 rivo volumio[3231]: info: Checking against Known Cards name Aug 27 19:22:16 rivo volumio[3231]: info: Checking against Known Cards name Aug 27 19:22:16 rivo volumio[3231]: info: Checking against Known Cards name Aug 27 19:22:16 rivo volumio[3231]: info: Checking against Known Cards name Aug 27 19:22:16 rivo volumio[3231]: info: Checking against Known Cards name Aug 27 19:22:16 rivo volumio[3231]: info: Adding Server instance for streaming Aug 27 19:22:16 rivo volumio[3231]: info: Adding TIDAL to Browse Sources Aug 27 19:22:16 rivo volumio[3231]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 19:22:16 rivo volumio[3231]: info: [1787851336043] CoreMusicLibrary::Adding element TIDAL Aug 27 19:22:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:16 rivo volumio[3231]: Cannot find translation for source QOBUZ Aug 27 19:22:16 rivo volumio[3231]: Cannot find translation for source TIDAL Aug 27 19:22:16 rivo volumio[3231]: info: Refreshing TIDAL token Aug 27 19:22:16 rivo sudo[3818]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Aug 27 19:22:16 rivo sudo[3818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3818]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo sudo[3821]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Aug 27 19:22:16 rivo sudo[3821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3821]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo sudo[3824]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Aug 27 19:22:16 rivo sudo[3824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3824]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo sudo[3827]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Aug 27 19:22:16 rivo sudo[3827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3827]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo volumio[3231]: info: Apply VIM3L Onboard LEDs Settings Aug 27 19:22:16 rivo volumio[3231]: info: Turning On Onboard LEDs Aug 27 19:22:16 rivo volumio[3231]: info: Starting CEC Event listener Aug 27 19:22:16 rivo sudo[3831]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 19:22:16 rivo sudo[3831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo volumio[3231]: info: Loading i18n strings for locale en Aug 27 19:22:16 rivo sudo[3831]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 19:22:16 rivo sudo[3737]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo sudo[3723]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo volumio[3231]: info: Volumio Calling Home Aug 27 19:22:16 rivo sudo[3835]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 19:22:16 rivo sudo[3835]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3835]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:16 rivo volumio[3231]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 27 19:22:16 rivo volumio[3231]: (node:3231) [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 19:22:16 rivo volumio[3231]: (Use `node --trace-deprecation ...` to show where the warning was created) Aug 27 19:22:16 rivo volumio[3231]: info: [now-playing] App is listening on port 4004. Aug 27 19:22:16 rivo volumio[3231]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Aug 27 19:22:16 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 19:22:16 rivo volumio[3231]: info: Serial port opened successfully Aug 27 19:22:16 rivo volumio[3231]: info: Sending serial start messages Aug 27 19:22:16 rivo sudo[3850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.14:/volume2/muzika2/MJUZA /mnt/NAS/MJUZA_2 Aug 27 19:22:16 rivo sudo[3850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo sudo[3852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto '192.168.0.14:/volume1/muzika1/stari D/MJUZA' /mnt/NAS/MJUZA_1 Aug 27 19:22:16 rivo sudo[3852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:16 rivo volumio[3231]: info: Reporting MCU Network Status: 0 Aug 27 19:22:16 rivo volumio[3231]: info: MP1 GPIO: Signalled system ready via GPIO Aug 27 19:22:17 rivo volumio[3231]: info: minidlna: Found miniDLNA version 1.3.0 Aug 27 19:22:17 rivo volumio[3231]: info: minidlna: Starting minidlna.service Aug 27 19:22:17 rivo systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 27 19:22:17 rivo systemd[1]: Starting rpc-statd.service - NFS status monitor for NFSv2/3 locking.... Aug 27 19:22:17 rivo volumio[3231]: error: Cannot start Volumio Streaming Daemon Aug 27 19:22:17 rivo volumio[3231]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 19:22:17 rivo volumio[3231]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 19:22:17 rivo volumio[3231]: info: RAAT Albumart path created successfully Aug 27 19:22:17 rivo sudo[3863]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Aug 27 19:22:17 rivo volumio[3231]: info: MPD Permissions set Aug 27 19:22:17 rivo sudo[3863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:17 rivo volumio[3231]: info: MPD Permissions set Aug 27 19:22:17 rivo rpc.statd[3868]: Version 2.6.2 starting Aug 27 19:22:17 rivo rpc.statd[3868]: Flags: TI-RPC Aug 27 19:22:17 rivo systemd[1]: Started rpc-statd.service - NFS status monitor for NFSv2/3 locking.. Aug 27 19:22:17 rivo volumio[3231]: info: Volumio called home Aug 27 19:22:17 rivo systemd[1]: Started minidlna.service - MiniDLNA lightweight DLNA/UPnP-AV server. Aug 27 19:22:17 rivo sudo[3863]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:17 rivo systemd[1]: Reloading. Aug 27 19:22:17 rivo volumio[3231]: error: MPD error: The expression evaluated to a falsy value: Aug 27 19:22:17 rivo volumio[3231]: assert.ok(self.idling) Aug 27 19:22:17 rivo volumio[3231]: error: The expression evaluated to a falsy value: Aug 27 19:22:17 rivo volumio[3231]: assert.ok(self.idling) Aug 27 19:22:17 rivo volumio[3231]: info: MPD running with PID3763 Aug 27 19:22:17 rivo volumio[3231]: ,establishing connection Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Aug 27 19:22:17 rivo volumio[3231]: info: Setting Device Volume Override Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 27 19:22:17 rivo volumio[3231]: info: Updating Volume Controller Parameters: Device: 5 Name: X-SABRE Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 27 19:22:17 rivo volumio[3231]: info: Disabling external Volume Control Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:17 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:17 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 19:22:17 rivo volumio[3231]: info: Setting Additional System Software info: Hardware Revision: 2.0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 19:22:17 rivo volumio[3231]: info: Setting HW Firmware info: undefined Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 19:22:17 rivo volumio[3231]: info: Setting HW Version info: 2.0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Aug 27 19:22:17 rivo volumio[3231]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.5 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Aug 27 19:22:17 rivo volumio[3231]: info: Setting HW Firmware info: 0.3.5 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Aug 27 19:22:17 rivo volumio[3231]: info: Setting HW Version info: 2.0 Aug 27 19:22:17 rivo volumio[3231]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Aug 27 19:22:17 rivo volumio[3231]: info: MCU Signalled Headphone Mode Disabled Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Aug 27 19:22:17 rivo volumio[3231]: info: Reporting Headphone State: false Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:22:17 rivo volumio[3231]: info: Updating RAAT Signal Path Aug 27 19:22:17 rivo volumio[3231]: error: Cannot write to RAAT Client: TypeError: Cannot read properties of undefined (reading 'write') Aug 27 19:22:17 rivo volumio[3231]: info: MCU Signalled Sleep Mode Disabled Aug 27 19:22:17 rivo volumio[3231]: info: Enabling Advanced system settings configuration Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Aug 27 19:22:17 rivo volumio[3231]: info: Additional UI Settings Added for plugin music_service/inputs Aug 27 19:22:17 rivo volumio[3231]: info: MCU Signalled Auto Boot Mode On Power Disabled Aug 27 19:22:17 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:17 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:17 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:17 rivo sudo[3904]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Aug 27 19:22:17 rivo sudo[3904]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:17 rivo volumio[3231]: error: updateQueue error: null Aug 27 19:22:17 rivo kernel: fb: osd[0] enable: 1 (Xorg) Aug 27 19:22:17 rivo sudo[3904]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:17 rivo volumio[3231]: 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: 2 Aug 27 19:22:17 rivo volumio[3231]: info: minidlna: systemctl start minidlna.service succeeded. Aug 27 19:22:17 rivo volumio[3231]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Aug 27 19:22:17 rivo volumio[3231]: 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: 2 Aug 27 19:22:17 rivo volumio[3231]: info: Received Get System Info Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:22:17 rivo volumio[3231]: info: Discovery: Getting this device information Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:17 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:22:17 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:17.859+02:00 level=INFO msg="system info for f86ae99edda56fd241971db7ab04d4e5" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.185 Aug 27 19:22:17 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:17.879+02:00 level=INFO msg="bootstrapping state" hasInternet=true Aug 27 19:22:17 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 00 f0 Aug 27 19:22:17 rivo volumio[3231]: info: Executing endpoint qc_getconfig Aug 27 19:22:17 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 27 19:22:17 rivo qobuz-connect[3796]: 20260827 19:22:17.966 [3796.3796] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 27 19:22:18 rivo volumio[3231]: info: Received Get System Info Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:22:18 rivo sudo[3913]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Aug 27 19:22:18 rivo sudo[3913]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:22:18 rivo volumio[3231]: info: Discovery: Getting this device information Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:22:18 rivo volumio[3231]: error: updateQueue error: null Aug 27 19:22:18 rivo volumio[3231]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 27 19:22:18 rivo volumio[3231]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 27 19:22:18 rivo volumio[3231]: info: Starting Shairport Sync Aug 27 19:22:18 rivo sudo[3913]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:18 rivo volumio[3231]: info: Starting Shairport Sync Aug 27 19:22:18 rivo volumio[3231]: info: Starting Shairport Sync Aug 27 19:22:18 rivo sudo[3916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 19:22:18 rivo sudo[3916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:18 rivo volumio-remote-updater[2951]: Test mode enabled Aug 27 19:22:18 rivo volumio-remote-updater[2951]: Alpha mode disabled Aug 27 19:22:18 rivo volumio-remote-updater[2951]: Alpha legacy test mode disabled Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.131 [3796.3796] INFO VolumeManager: [0xab84df68]: Setting new playback volume: 75 Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.131 [3796.3796] INFO VolumeManager: [0xab84df68]: Setting new mute state: 0 Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.131 [3796.3796] INFO AudioStreamManager: [0xab84dcc0]: Setting new audio download buffer size: 1048576 Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.131 [3796.3796] INFO QobuzConnect: [0xab84e830]: Client initialized! Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.131 [3796.3796] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Aug 27 19:22:18 rivo sudo[3919]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 19:22:18 rivo sudo[3919]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:18 rivo volumio[3231]: info: Adding Inputs via Serial API Aug 27 19:22:18 rivo sudo[3921]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 27 19:22:18 rivo sudo[3921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.197 [3796.3796] INFO LocalConfigManager: [0xab84d9e8]: Starting Local Configuration server Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.197 [3796.3796] INFO SampleApp: Starting Local configuration server Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.198 [3796.3796] INFO SampleApp: Connected to UNIX socket client 0xab838908 Aug 27 19:22:18 rivo qobuz-connect[3796]: 20260827 19:22:18.198 [3796.3796] INFO SampleApp: Playback volume changed: 75 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::servicePushState Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: 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 27 19:22:18 rivo volumio[3231]: verbose: CURRENT POSITION 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::syncState stateService stop Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::syncState currentStatus stop Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: No code Aug 27 19:22:18 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumiosetSourceActiveno-source Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 19:22:18 rivo volumio[3231]: Cannot find translation for source QOBUZ Aug 27 19:22:18 rivo volumio[3231]: Cannot find translation for source TIDAL Aug 27 19:22:18 rivo volumio[3231]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 19:22:18 rivo volumio[3231]: info: Received Get System Info Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 27 19:22:18 rivo volumio[3231]: info: Discovery: Getting this device information Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:18 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 27 19:22:18 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 27 19:22:18 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:18.414+02:00 level=INFO msg="enabling local network discovery" Aug 27 19:22:18 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:18.415+02:00 level=INFO msg="enabling BLE discovery" Aug 27 19:22:18 rivo volumio[3231]: info: TidalConnect service stoped! Aug 27 19:22:19 rivo volumio[3231]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 27 19:22:19 rivo volumio[3231]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 27 19:22:19 rivo volumio[3231]: info: Turning Off Onboard LEDs Aug 27 19:22:19 rivo sudo[3960]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 27 19:22:19 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:19 rivo sudo[3963]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Aug 27 19:22:19 rivo sudo[3963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:19 rivo sudo[3963]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo sudo[3967]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Aug 27 19:22:19 rivo sudo[3967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:19 rivo sudo[3967]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo volumio[3231]: info: Onboard LEDs initialized Aug 27 19:22:19 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Aug 27 19:22:19 rivo volumio5-onboarding[3748]: time=2026-08-27T19:22:19.490+02:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 27 19:22:19 rivo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 27 19:22:19 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 27 19:22:19 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo systemd[1]: shairport-sync.service: Deactivated successfully. Aug 27 19:22:19 rivo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 19:22:19 rivo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 27 19:22:19 rivo sudo[3916]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo sudo[3919]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo sudo[3921]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:19 rivo volumio[3231]: info: Shairport-Sync Started Aug 27 19:22:19 rivo volumio[3231]: Error adding Membership: Error: addMembership EINVAL Aug 27 19:22:19 rivo volumio[3231]: info: Shairport-Sync Started Aug 27 19:22:19 rivo volumio[3231]: info: Shairport-Sync Started Aug 27 19:22:19 rivo volumio[3231]: info: Reporting MCU Network Status: 1 Aug 27 19:22:19 rivo volumio[3231]: info: Volumio Network Manager: Network status updated: 1 Aug 27 19:22:20 rivo volumio[3231]: info: Executing endpoint tc_getconfig Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 27 19:22:20 rivo vtcs[3978]: STARTING TidalConnect services, version: 1.6.1 Aug 27 19:22:20 rivo vtcs[3978]: STARTED TidalConnect services. Aug 27 19:22:20 rivo volumio[3231]: info: Executing endpoint tc_connect Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 27 19:22:20 rivo volumio[3231]: info: Connecting to TidalConnect Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::servicePushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: 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 27 19:22:20 rivo volumio[3231]: verbose: CURRENT POSITION 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::syncState stateService stop Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::syncState currentStatus stop Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: No code Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::servicePushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: 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 27 19:22:20 rivo volumio[3231]: verbose: CURRENT POSITION 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::syncState stateService stop Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::syncState currentStatus stop Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: No code Aug 27 19:22:20 rivo volumio[3231]: info: CoreStateMachine::pushState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioPushState Aug 27 19:22:20 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:20 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:20 rivo volumio[3231]: info: MRS: Getting audio outputs on start Aug 27 19:22:20 rivo volumio[3231]: info: MRS: Requesting all other devices output Aug 27 19:22:21 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 19:22:21 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 19:22:21 rivo sudo[4039]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 19:22:21 rivo sudo[4039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:21 rivo sudo[4041]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 19:22:21 rivo sudo[4041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:21 rivo sudo[4039]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:21 rivo sudo[4041]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:21 rivo volumio[3231]: 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: 3 Aug 27 19:22:22 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 19:22:22 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 19:22:22 rivo volumio[3231]: info: TidalConnect service started! Aug 27 19:22:23 rivo kernel: fb: mem_free_work, free memory: addr:800000 Aug 27 19:22:24 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 19:22:24 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 19:22:24 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 19:22:24 rivo sudo[3718]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:24 rivo volumio[3231]: info: Upmpdcli Daemon Started Aug 27 19:22:24 rivo sudo[4093]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 19:22:24 rivo sudo[4093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:24 rivo sudo[4095]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 19:22:24 rivo sudo[4095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:24 rivo sudo[4093]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:24 rivo sudo[4095]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:25 rivo volumio[3231]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 19:22:27 rivo volumio[3231]: info: Checking for updated MCU Firmware Aug 27 19:22:27 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 19:22:27 rivo volumio[3231]: info: Firware on device is on latest version, no need to update Aug 27 19:22:27 rivo volumio[3231]: 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 19:22:32 rivo sudo[3852]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:32 rivo sudo[3850]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:35 rivo volumio[3231]: info: Cannot mount NAS MJUZA_2 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 19:22:35 rivo volumio[3231]: info: Cannot mount NAS MJUZA_1 at system boot, trial number 2 ,retrying in 5 seconds Aug 27 19:22:38 rivo volumio[3231]: info: Discovery: adding 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 19:22:38 rivo volumio[3231]: info: Discovery: Found device Rivo Aug 27 19:22:38 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:38 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:38 rivo volumio[3231]: info: MRS: Pushing multiroomSync output for this device Aug 27 19:22:38 rivo volumio[3231]: info: MRS: Pushing multiroomSync output Aug 27 19:22:38 rivo volumio[3231]: info: Adding audio output: Aug 27 19:22:38 rivo volumio[3231]: info: Adding audio output: Aug 27 19:22:38 rivo volumio[3231]: info: Discovery: this is already registered, 03ab7cbd-71ad-4a1f-bf87-b8c8b0e4a6d1 Aug 27 19:22:38 rivo volumio[3231]: info: Discovery: Found device Rivo Aug 27 19:22:38 rivo volumio[3231]: info: CoreCommandRouter::volumioGetState Aug 27 19:22:38 rivo volumio[3231]: info: CorePlayQueue::getTrack 0 Aug 27 19:22:41 rivo sudo[4446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.0.14:/volume2/muzika2/MJUZA /mnt/NAS/MJUZA_2 Aug 27 19:22:41 rivo sudo[4448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto '192.168.0.14:/volume1/muzika1/stari D/MJUZA' /mnt/NAS/MJUZA_1 Aug 27 19:22:41 rivo sudo[4448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:41 rivo sudo[4446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 19:22:41 rivo sudo[4446]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:41 rivo sudo[4448]: pam_unix(sudo:session): session closed for user root Aug 27 19:22:41 rivo nfsrahead[4460]: setting /mnt/NAS/MJUZA_1 readahead to 128 Aug 27 19:22:41 rivo nfsrahead[4458]: setting /mnt/NAS/MJUZA_2 readahead to 128 Aug 27 19:22:44 rivo volumio[3231]: info: Access Token successfully retrieved Aug 27 19:22:44 rivo volumio[3231]: info: Stopping AccessToken refresher cron for QOBUZ Aug 27 19:22:44 rivo volumio[3231]: info: AccessToken refresher cron started for QOBUZ Aug 27 19:22:44 rivo volumio[3231]: info: Adding QOBUZ REST API Endpoints Aug 27 19:22:45 rivo volumio[3231]: info: Access Token successfully retrieved Aug 27 19:22:46 rivo volumio[3231]: error: Hi Res Audio Failed Login: [object Object] Aug 27 19:22:46 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 19:22:46 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:22:46 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 19:22:46 rivo volumio[3231]: error: Plugin music_service tidal failed to complete 'onStart' in a timely fashion Aug 27 19:22:46 rivo volumio[3231]: info: ------------------------------------------- Aug 27 19:22:46 rivo volumio[3231]: info: ----- MyVolumio plugins startup ---- Aug 27 19:22:46 rivo volumio[3231]: info: ------------------------------------------- Aug 27 19:22:46 rivo volumio[3231]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 19:22:51 rivo volumio[3231]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 27 19:22:54 rivo volumio[3231]: 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 27 19:23:07 rivo volumio[3231]: info: MRS: Found cast device: CHSTB02-88837a1d9d2011fd891ba3df03e82fab Aug 27 19:23:07 rivo volumio[3231]: info: Adding audio output: Aug 27 19:23:07 rivo volumio[3231]: info: MRS: Found cast device: CHSTB02-2c07cb952969f3ad2bbb27b23c87610d Aug 27 19:23:07 rivo volumio[3231]: info: Adding audio output: Aug 27 19:23:07 rivo volumio[3231]: error: MyVolumio Plugin failed to start in a timely fashion Aug 27 19:23:07 rivo volumio[3231]: [Metrics] CommandRouter: 82s 145.13ms Aug 27 19:23:07 rivo volumio[3231]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 19:23:07 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:07 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:23:07 rivo volumio[3231]: info: CoreCommandRouter::Close All Modals sent Aug 27 19:23:07 rivo volumio[3231]: info: CoreCommandRouter::Close All Modals sent Aug 27 19:23:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 19:23:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 19:23:09 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 19:23:16 rivo volumio[3231]: info: Successfully retrieved User Session From TIDAL Aug 27 19:23:16 rivo volumio[3231]: 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 27 19:23:16 rivo volumio[3231]: info: BOOT COMPLETED Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 19:23:16 rivo volumio[3231]: info: Not Reporting Auto name since its the default one Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 19:23:25 rivo volumio[3231]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 19:23:26 rivo volumio[3231]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 19:23:26 rivo volumio[3231]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 19:23:26 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:23:26 rivo volumio[3231]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 19:23:26 rivo volumio[3231]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 19:23:26 rivo volumio[3231]: info: MyVolumio login type: Token Aug 27 19:23:26 rivo volumio[3231]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 19:23:26 rivo volumio[3231]: 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 19:23:26 rivo volumio[3231]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 19:23:26 rivo volumio[3231]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 19:23:26 rivo volumio[3231]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Aug 27 19:23:26 rivo volumio[3231]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Aug 27 19:23:26 rivo volumio[3231]: at new NodeError (node:internal/errors:405:5) Aug 27 19:23:26 rivo volumio[3231]: at assert (node:internal/assert:14:11) Aug 27 19:23:26 rivo volumio[3231]: at internalConnectMultiple (node:net:1118:3) Aug 27 19:23:26 rivo volumio[3231]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Aug 27 19:23:26 rivo volumio[3231]: at listOnTimeout (node:internal/timers:575:11) Aug 27 19:23:26 rivo volumio[3231]: at process.processTimers (node:internal/timers:514:7) { Aug 27 19:23:26 rivo volumio[3231]: code: 'ERR_INTERNAL_ASSERTION' Aug 27 19:23:26 rivo volumio[3231]: } Aug 27 19:23:26 rivo volumio[3231]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 19:23:27 rivo sudo[4586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 19:22' Aug 27 19:23:27 rivo sudo[4586]: 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"