Oct 02 14:55:11 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 14:55:11 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 14:55:11 rivo systemd[1]: Reached target multi-user.target - Multi-User System. Oct 02 14:55:11 rivo systemd[1]: Reached target graphical.target - Graphical Interface. Oct 02 14:55:11 rivo systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 02 14:55:11 rivo systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 02 14:55:11 rivo systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 02 14:55:11 rivo ntpd[3190]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 02 14:55:11 rivo systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 02 14:55:11 rivo ntpd[3190]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 02 14:55:11 rivo ntpd[3190]: DNS: Pool taking: 192.86.14.67 Oct 02 14:55:11 rivo ntpd[3190]: DNS: Pool skipping: 46.175.224.7 Oct 02 14:55:11 rivo ntpd[3190]: DNS: Pool skipping: 51.68.141.5 Oct 02 14:55:11 rivo ntpd[3190]: DNS: Pool skipping: 89.161.47.136 Oct 02 14:55:11 rivo ntpd[3190]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 02 14:55:11 rivo systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 02 14:55:11 rivo systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 02 14:55:11 rivo systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 02 14:55:11 rivo systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 02 14:55:11 rivo dbus-daemon[2892]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 02 14:55:11 rivo systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 02 14:55:11 rivo bluealsa[3478]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 02 14:55:11 rivo bluealsa[3478]: main.c:663: Starting main dispatching loop Oct 02 14:55:11 rivo systemd[1]: Started bluealsa.service - BlueALSA service. Oct 02 14:55:11 rivo systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 02 14:55:11 rivo systemd[1]: Startup finished in 13.349s (kernel) + 17.288s (userspace) = 30.638s. Oct 02 14:55:11 rivo bluealsa[3478]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 02 14:55:11 rivo bluealsa[3478]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 02 14:55:11 rivo bluealsa[3478]: ../src/ba-adapter.c:53: Couldn't get HCI version: Network is down Oct 02 14:55:11 rivo bluealsa[3478]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 02 14:55:11 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 02 14:55:11 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 02 14:55:11 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 02 14:55:11 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 02 14:55:11 rivo bluetoothd[3471]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 02 14:55:11 rivo bluetoothd[3471]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 02 14:55:11 rivo bluetoothd[3471]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 02 14:55:11 rivo bluetoothd[3471]: Endpoint registered: sender=:1.10 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 02 14:55:11 rivo volumio-remote-updater[2920]: [2026-10-02 14:55:11] [connect] Successful connection Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "network"... Oct 02 14:55:12 rivo volumio[3297]: info: Refreshing Cached IP Addresses Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "services"... Oct 02 14:55:12 rivo sudo[3512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 14:55:12 rivo sudo[3512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "volumio5onboarding"... Oct 02 14:55:12 rivo sudo[3514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 14:55:12 rivo sudo[3514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:12 rivo sudo[3512]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:12 rivo sudo[3514]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "artwork_companion"... Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "alsa_controller"... Oct 02 14:55:12 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "wizard"... Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "networkfs"... Oct 02 14:55:12 rivo volumio[3297]: info: Starting Udev Watcher for removable devices Oct 02 14:55:12 rivo sudo[3540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=robo27,password=Batuser1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.1.196/docker/music /mnt/NAS/NAS Oct 02 14:55:12 rivo sudo[3540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:12 rivo volumio[3297]: info: Ignoring mount for partition: BOOT Oct 02 14:55:12 rivo volumio[3297]: info: Ignoring mount for partition: volumio Oct 02 14:55:12 rivo volumio[3297]: info: Ignoring mount for partition: volumio_data Oct 02 14:55:12 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "volumio_command_line_client"... Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "upnp"... Oct 02 14:55:12 rivo volumio[3297]: info: [1790945712575] Starting Upmpd Daemon Oct 02 14:55:12 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "my_music"... Oct 02 14:55:12 rivo volumio[3297]: info: Loading plugin "mpd"... Oct 02 14:55:13 rivo volumio[3297]: info: Plugin upnp_browser is not enabled Oct 02 14:55:13 rivo volumio[3297]: info: Plugin bluetooth is not enabled Oct 02 14:55:13 rivo volumio[3297]: info: Loading plugin "alarm-clock"... Oct 02 14:55:13 rivo volumio[3297]: info: Loading plugin "manifestui"... Oct 02 14:55:13 rivo volumio[3297]: info: Loading plugin "metavolumio"... Oct 02 14:55:14 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 02 14:55:14 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:14 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:14 rivo upmpdcli[3551]: Could not open config: /tmp/upmpdcli.conf Oct 02 14:55:14 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 14:55:14 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 14:55:15 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 02 14:55:15 rivo volumio[3297]: info: Loading plugin "novaui"... Oct 02 14:55:15 rivo volumio[3297]: info: Applying required configuration parameters for plugin novaui Oct 02 14:55:15 rivo volumio[3297]: info: Plugin airplay_emulation is not enabled Oct 02 14:55:15 rivo volumio[3297]: info: Plugin cd_controller is not enabled Oct 02 14:55:15 rivo volumio[3297]: info: Loading plugin "last_100"... Oct 02 14:55:15 rivo volumio[3297]: info: Loading plugin "qobuzconnect"... Oct 02 14:55:15 rivo volumio[3297]: info: Plugin raat is not enabled Oct 02 14:55:15 rivo volumio[3297]: info: Loading plugin "streaming_services"... Oct 02 14:55:17 rivo volumio[3297]: info: Loading plugin "tidalconnect"... Oct 02 14:55:17 rivo volumio[3297]: info: Loading plugin "webradio"... Oct 02 14:55:18 rivo volumio[3297]: info: Loading plugin "i2s_dacs"... Oct 02 14:55:18 rivo volumio[3297]: info: I2S DAC not set, start Auto-detection Oct 02 14:55:18 rivo volumio[3297]: info: Loading plugin "volumiodiscovery"... Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** For more information see Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 14:55:18 rivo node[3297]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 02 14:55:18 rivo volumio[3297]: *** WARNING *** For more information see Oct 02 14:55:18 rivo node[3297]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 14:55:18 rivo node[3297]: *** WARNING *** For more information see Oct 02 14:55:18 rivo node[3297]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 02 14:55:18 rivo node[3297]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 02 14:55:18 rivo node[3297]: *** WARNING *** For more information see Oct 02 14:55:18 rivo volumio[3297]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 02 14:55:18 rivo volumio[3297]: info: Discovery: Started advertising with name: Rivo Oct 02 14:55:18 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 02 14:55:18 rivo volumio[3297]: info: Loading plugin "rp2"... Oct 02 14:55:18 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 02 14:55:18 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 02 14:55:18 rivo sudo[3540]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:21 rivo volumio[3297]: info: Loading plugin "soundcloud"... Oct 02 14:55:22 rivo volumio[3297]: info: Loading plugin "squeezelite_mc"... Oct 02 14:55:23 rivo volumio[3297]: info: Plugin multiroom is not enabled Oct 02 14:55:23 rivo volumio[3297]: info: Loading plugin "outputs"... Oct 02 14:55:23 rivo volumio[3297]: info: Loading plugin "albumart"... Oct 02 14:55:23 rivo volumio[3297]: info: Plugin example_plugin is not enabled Oct 02 14:55:23 rivo volumio[3297]: info: Loading plugin "hi_res_audio"... Oct 02 14:55:24 rivo volumio[3575]: Forking 3 albumart workers Oct 02 14:55:25 rivo volumio[3584]: Starting albumart workers Oct 02 14:55:25 rivo volumio[3297]: info: Applying required configuration parameters for plugin hi_res_audio Oct 02 14:55:25 rivo volumio[3297]: info: Loading plugin "inputs"... Oct 02 14:55:25 rivo volumio[3582]: Starting albumart workers Oct 02 14:55:25 rivo volumio[3583]: Starting albumart workers Oct 02 14:55:26 rivo volumio[3297]: info: Loading plugin "qobuz"... Oct 02 14:55:26 rivo volumio-remote-updater[2920]: [2026-10-02 14:55:26] [connect] Successful connection Oct 02 14:55:27 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 02 14:55:28 rivo volumio[3297]: info: Plugin smart_inputs is not enabled Oct 02 14:55:28 rivo volumio[3297]: info: Loading plugin "tidal"... Oct 02 14:55:29 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 02 14:55:29 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:29 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:29 rivo upmpdcli[3629]: Could not open config: /tmp/upmpdcli.conf Oct 02 14:55:29 rivo volumio[3297]: info: Loading plugin "rivocontrol"... Oct 02 14:55:29 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 02 14:55:29 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 02 14:55:30 rivo volumio[3297]: info: Adding this device properties Oct 02 14:55:30 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 02 14:55:30 rivo volumio[3297]: info: Setting Additional Device Volumio Properties: [object Object] Oct 02 14:55:30 rivo volumio[3297]: info: Loading plugin "updater_comm"... Oct 02 14:55:30 rivo volumio[3297]: info: Loading plugin "cec_controller"... Oct 02 14:55:30 rivo volumio[3297]: info: Applying required configuration parameters for plugin cec_controller Oct 02 14:55:30 rivo volumio[3297]: info: Plugin mpdemulation is not enabled Oct 02 14:55:30 rivo volumio[3297]: info: Loading plugin "rest_api"... Oct 02 14:55:30 rivo volumio[3297]: info: Loading plugin "websocket"... Oct 02 14:55:30 rivo volumio[3297]: info: Starting Socket.io Server version 1.7.4 Oct 02 14:55:30 rivo volumio[3297]: info: Loading plugin "fusiondsp"... Oct 02 14:55:31 rivo volumio[3297]: info: Applying required configuration parameters for plugin fusiondsp Oct 02 14:55:31 rivo volumio[3297]: info: Loading plugin "rad357pl"... Oct 02 14:55:31 rivo volumio[3297]: info: Applying required configuration parameters for plugin rad357pl Oct 02 14:55:31 rivo volumio[3297]: info: [1790945731614] [rad357pl] API delay: 5 Oct 02 14:55:31 rivo volumio[3297]: info: Loading plugin "radio_fip"... Oct 02 14:55:31 rivo volumio[3297]: info: Applying required configuration parameters for plugin radio_fip Oct 02 14:55:31 rivo volumio[3297]: info: [radio_fip] onVolumioStart Oct 02 14:55:31 rivo volumio[3297]: info: Loading plugin "soloist_connect"... Oct 02 14:55:32 rivo volumio[3297]: info: Loading plugin "flightmode"... Oct 02 14:55:32 rivo volumio[3297]: info: Loading plugin "randomizer"... Oct 02 14:55:32 rivo volumio[3297]: info: Applying required configuration parameters for plugin randomizer Oct 02 14:55:33 rivo volumio[3297]: info: Loading i18n strings for locale en Oct 02 14:55:33 rivo volumio[3297]: Updating browse sources language Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::initPlayerControls Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:55:33 rivo volumio[3297]: Express server listening on port 3000 Oct 02 14:55:33 rivo volumio[3297]: [Metrics] WebUI: 26s 812.08ms Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::resetVolumioState Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::getcurrentVolume Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:33 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 14:55:33 rivo sudo[3662]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 14:55:33 rivo sudo[3662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:33 rivo volumio[3297]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 02 14:55:33 rivo volumio[3297]: info: Completed loading Core Plugins Oct 02 14:55:33 rivo volumio[3297]: info: Preparing to generate the ALSA configuration file Oct 02 14:55:33 rivo sudo[3664]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 14:55:33 rivo sudo[3664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:33 rivo sudo[3662]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:33 rivo sudo[3664]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:33 rivo volumio[3297]: info: Volumio Network Manager: Network status updated: 1 Oct 02 14:55:33 rivo volumio[3297]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Oct 02 14:55:33 rivo volumio[3297]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 02 14:55:33 rivo volumio[3297]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 14:55:33 rivo volumio[3297]: info: Reading ALSA contributions from plugins. Oct 02 14:55:33 rivo volumio[3297]: info: Reloading queue from file Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::setRepeat false single undefined Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:33 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::setRandom false Oct 02 14:55:33 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:33 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 14:55:33 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:33 rivo volumio[3297]: info: Asound.conf file unchanged, so no further update is needed Oct 02 14:55:33 rivo volumio[3297]: info: Output device has changed, restarting MPD Oct 02 14:55:33 rivo sudo[3676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 02 14:55:33 rivo sudo[3676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo volumio[3297]: info: ___________ START PLUGINS ___________ Oct 02 14:55:34 rivo sudo[3681]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 14:55:34 rivo sudo[3681]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3681]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio[3297]: info: [artwork_companion] started; {"version":1,"theme":"dark","ambient":{"on":true,"delay":1,"layout":"cover","clock":"24","night":true,"nightFrom":"22:00","nightTo":"07:00"}} Oct 02 14:55:34 rivo volumio[3297]: info: ControllerMpd::onStart: Initializing MPD Oct 02 14:55:34 rivo volumio[3297]: info: Creating MPD Configuration file Oct 02 14:55:34 rivo sudo[3683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 14:55:34 rivo sudo[3683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 02 14:55:34 rivo sudo[3686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo volumio[3297]: info: Adding METAVOLUMIO REST API Endpoints Oct 02 14:55:34 rivo volumio[3297]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 02 14:55:34 rivo volumio[3297]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 02 14:55:34 rivo volumio[3297]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 02 14:55:34 rivo volumio[3297]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 02 14:55:34 rivo volumio[3297]: info: Nova UI: unpacking bundled version 0.260915.4 Oct 02 14:55:34 rivo sudo[3688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 14:55:34 rivo sudo[3688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3688]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734109] CoreMusicLibrary::Adding element Last_100 Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Oct 02 14:55:34 rivo sudo[3692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 14:55:34 rivo sudo[3692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo volumio[3297]: info: QobuzConnect: Starting Qobuz Connect socket and service Oct 02 14:55:34 rivo volumio[3297]: info: Streaming services startup Oct 02 14:55:34 rivo volumio[3297]: info: Starting Streaming Daemon Oct 02 14:55:34 rivo sudo[3701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 14:55:34 rivo sudo[3701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 14:55:34 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 14:55:34 rivo sudo[3705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 02 14:55:34 rivo sudo[3705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 02 14:55:34 rivo sudo[3686]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734277] CoreMusicLibrary::Adding element Webradio Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 14:55:34 rivo volumio[3297]: info: Initializing BBC Radios Oct 02 14:55:34 rivo sudo[3720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 14:55:34 rivo sudo[3720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3701]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo sudo[3705]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo sudo[3724]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 14:55:34 rivo sudo[3724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 14:55:34 rivo sudo[3720]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734459] CoreMusicLibrary::Adding element Radio Paradise (RP2) Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734499] CoreMusicLibrary::Adding element SoundCloud Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:34 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 14:55:34 rivo sudo[3724]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio[3297]: info: [squeezelite_mc] Starting proxy server... Oct 02 14:55:34 rivo volumio[3297]: error: Hi Res Audio Failed Login: Missing Login Data Oct 02 14:55:34 rivo volumio[3297]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 02 14:55:34 rivo volumio[3297]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 02 14:55:34 rivo volumio[3297]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 02 14:55:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Oct 02 14:55:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Oct 02 14:55:34 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Oct 02 14:55:34 rivo volumio[3297]: info: Touch Event Listener Process Starting Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734621] CoreMusicLibrary::Adding element QOBUZ Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:34 rivo volumio[3297]: info: Refreshing QOBUZ token Oct 02 14:55:34 rivo sudo[3743]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 02 14:55:34 rivo sudo[3743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo volumio[3297]: info: Adding TIDAL to Browse Sources Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:34 rivo volumio[3297]: info: [1790945734684] CoreMusicLibrary::Adding element TIDAL Oct 02 14:55:34 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:34 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:34 rivo volumio[3297]: info: Refreshing TIDAL token Oct 02 14:55:34 rivo sudo[3755]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 02 14:55:34 rivo sudo[3755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3755]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo sudo[3712]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 14:55:34 rivo sudo[3712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 14:55:34 rivo sudo[3759]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 02 14:55:34 rivo sudo[3712]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo sudo[3764]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 02 14:55:34 rivo sudo[3764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:34 rivo sudo[3764]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:34 rivo volumio5-onboarding[3719]: time=2026-10-02T14:55:34.979+02:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 02 14:55:35 rivo sudo[3774]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 02 14:55:35 rivo sudo[3774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:35 rivo sudo[3774]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:35 rivo sudo[3781]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 02 14:55:35 rivo sudo[3781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:35 rivo sudo[3781]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:35 rivo volumio[3297]: info: Apply VIM3L Onboard LEDs Settings Oct 02 14:55:35 rivo volumio[3297]: info: Turning On Onboard LEDs Oct 02 14:55:35 rivo volumio[3297]: info: Starting CEC Event listener Oct 02 14:55:35 rivo sudo[3789]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 02 14:55:35 rivo sudo[3789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:35 rivo sudo[3789]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:35 rivo sudo[3793]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 02 14:55:35 rivo sudo[3793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:35 rivo sudo[3793]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:35 rivo volumio[3297]: info: FusionDsp - CamillaDSP binary found. Oct 02 14:55:35 rivo volumio[3297]: info: Loading i18n strings for locale en Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:35 rivo volumio[3297]: info: FusionDsp - mixtype--------------------- None Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:35 rivo volumio[3297]: info: [1790945735240] CoreMusicLibrary::Adding element RADIO 357 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source RADIO 357 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 14:55:35 rivo volumio[3297]: info: [radio_fip] Loaded 13 stations Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesfip Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source RADIO 357 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:35 rivo volumio[3297]: info: [1790945735258] CoreMusicLibrary::Adding element FIP Radio Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source RADIO 357 Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source FIP Radio Oct 02 14:55:35 rivo volumio[3297]: info: SoloistConnect: plugin=0.9.0 shim=0.2.9 Oct 02 14:55:35 rivo volumio[3297]: info: SoloistConnect: freshness armed period=24h Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 02 14:55:35 rivo volumio[3297]: info: [1790945735329] CoreMusicLibrary::Adding element Randomizer Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source RADIO 357 Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source FIP Radio Oct 02 14:55:35 rivo volumio[3297]: Cannot find translation for source Randomizer Oct 02 14:55:35 rivo volumio[3297]: info: Volumio Calling Home Oct 02 14:55:35 rivo volumio[3297]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Oct 02 14:55:35 rivo volumio[3297]: info: [squeezelite_mc] Proxy server started on port 38771 Oct 02 14:55:35 rivo volumio[3297]: info: Preparing to generate the ALSA configuration file Oct 02 14:55:35 rivo volumio[3297]: info: [radio_fip] Started Oct 02 14:55:35 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 08 00 46 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 14:55:35 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:35 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:35 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:35 rivo volumio[3297]: verbose: [squeezelite_mc] PlayerFinder: fork child process at /data/plugins/music_service/squeezelite_mc/dist/lib/PlayerFinderChild.js Oct 02 14:55:35 rivo volumio[3297]: info: Discovery: adding a3b5496c-67f8-4f50-bfbf-ee3fb633b17a Oct 02 14:55:35 rivo volumio[3297]: info: Discovery: Found device Rivo Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:35 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:35 rivo volumio[3297]: info: Discovery: this is already registered, a3b5496c-67f8-4f50-bfbf-ee3fb633b17a Oct 02 14:55:35 rivo volumio[3297]: info: Discovery: Found device Rivo Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:35 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:35 rivo volumio[3297]: info: Serial port opened successfully Oct 02 14:55:35 rivo volumio[3297]: info: Sending serial start messages Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:35 rivo volumio[3297]: info: Reporting MCU Network Status: 1 Oct 02 14:55:35 rivo volumio[3297]: info: MP1 GPIO: Signalled system ready via GPIO Oct 02 14:55:35 rivo volumio[3297]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 02 14:55:35 rivo volumio[3297]: info: Reading ALSA contributions from plugins. Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , soloistMeteringWanted Oct 02 14:55:35 rivo volumio[3297]: info: SoloistConnect: writeEnvFile cache_location="disk" cache_size_mb=1024 ceiling=464 -> ramMb=0 CACHE_SIZE=1024 Oct 02 14:55:35 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:35 rivo volumio[3297]: error: Cannot start Volumio Streaming Daemon Oct 02 14:55:35 rivo volumio[3297]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 02 14:55:35 rivo volumio[3297]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 02 14:55:35 rivo sudo[3828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -sf /data/plugins/system_controller/flightmode/units/volumio-flightmode.service /etc/systemd/system/volumio-flightmode.service Oct 02 14:55:36 rivo sudo[3828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:36 rivo volumio[3297]: info: MPD Permissions set Oct 02 14:55:36 rivo volumio[3297]: info: MPD Permissions set Oct 02 14:55:36 rivo sudo[3830]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl restart soloist.service Oct 02 14:55:36 rivo sudo[3828]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:36 rivo sudo[3830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:36 rivo volumio[3297]: info: Executing endpoint qc_getconfig Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 14:55:36 rivo systemd[1]: Starting soloist.service - Spotify Soloist daemon (Volumio 4 ALSA)... Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.167 [3740.3740] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 14:55:36 rivo volumio[3297]: info: Volumio called home Oct 02 14:55:36 rivo volumio[3297]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 14:55:36 rivo volumio[3297]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 14:55:36 rivo volumio[3297]: 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 Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.361 [3740.3740] INFO VolumeManager: [0xabfaf168]: Setting new playback volume: 75 Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.362 [3740.3740] INFO VolumeManager: [0xabfaf168]: Setting new mute state: 0 Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.362 [3740.3740] INFO AudioStreamManager: [0xabfaeec0]: Setting new audio download buffer size: 1048576 Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.364 [3740.3740] INFO QobuzConnect: [0xabfafa30]: Client initialized! Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.364 [3740.3740] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 02 14:55:36 rivo systemd[1]: Started soloist.service - Spotify Soloist daemon (Volumio 4 ALSA). Oct 02 14:55:36 rivo sudo[3830]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 14:55:36 rivo sudo[3851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -sf /data/plugins/system_controller/flightmode/units/volumio-flightmode.path /etc/systemd/system/volumio-flightmode.path Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.405 [3740.3740] INFO LocalConfigManager: [0xabfaebe8]: Starting Local Configuration server Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.405 [3740.3740] INFO SampleApp: Starting Local configuration server Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.405 [3740.3740] INFO SampleApp: Connected to UNIX socket client 0xabf99908 Oct 02 14:55:36 rivo qobuz-connect[3740]: 20261002 14:55:36.405 [3740.3740] INFO SampleApp: Playback volume changed: 75 Oct 02 14:55:36 rivo sudo[3851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:36 rivo sudo[3851]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:36 rivo launch-soloist.sh[3858]: SoloistConnect: plugin=0.9.0 shim=0.2.9 rev=12387e557e7524f1055bf92257c4b139a0273ba1 userspace=armhf device=plug:volumio tlength_cap=500ms external_volume=false trim=0dB diag=off uname=aarch64 Oct 02 14:55:36 rivo launch-soloist.sh[3886]: SoloistConnect: engine_prefs loudness=off crossfade=off stores=3 Oct 02 14:55:36 rivo volumio[3297]: 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 Oct 02 14:55:36 rivo volumio[3297]: info: Received Get System Info Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:55:36 rivo volumio[3297]: info: Discovery: Getting this device information Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:36 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:55:36 rivo volumio5-onboarding[3719]: time=2026-10-02T14:55:36.726+02:00 level=INFO msg="system info for addffede8d3a613d53f06b977d8212e3" deviceName=Rivo deviceVariant=rivo deviceModel="Volumio Rivo" softwareVersion=4.209 Oct 02 14:55:36 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 08 00 46 Oct 02 14:55:36 rivo volumio[3297]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Oct 02 14:55:36 rivo volumio5-onboarding[3719]: time=2026-10-02T14:55:36.752+02:00 level=INFO msg="bootstrapping state" hasInternet=true Oct 02 14:55:36 rivo volumio[3297]: info: Access Token successfully retrieved Oct 02 14:55:36 rivo volumio[3297]: info: Stopping AccessToken refresher cron for QOBUZ Oct 02 14:55:36 rivo volumio[3297]: info: AccessToken refresher cron started for QOBUZ Oct 02 14:55:36 rivo volumio[3297]: info: Adding QOBUZ REST API Endpoints Oct 02 14:55:36 rivo volumio[3297]: info: Received Get System Info Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:55:36 rivo volumio[3297]: info: Discovery: Getting this device information Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:36 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:55:36 rivo launch-soloist.sh[3858]: SoloistConnect: interpreter=/data/soloist/sysroot/usr/lib/ld-linux-armhf.so.3 Oct 02 14:55:36 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:36 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:36 rivo volumio[3297]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Oct 02 14:55:36 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:36.998: soloist 1.3.8.81 build 1790424061 (20260926) (g5c3a2053ac) (linux/arm) Oct 02 14:55:36 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:36.999: client expires in 83 days Oct 02 14:55:37 rivo volumio[3297]: info: SoloistConnect: soloist daemon started Oct 02 14:55:37 rivo volumio[3297]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Oct 02 14:55:37 rivo volumio[3297]: info: Access Token successfully retrieved Oct 02 14:55:37 rivo sudo[3906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 02 14:55:37 rivo sudo[3906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:37 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:37.176: api: websocket server listening on 127.0.0.1:9878 Oct 02 14:55:37 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:37.176: ready, device_name=Volumio device_id=720cc124-5b7b-4809-99c3-5cf85284bb0f Oct 02 14:55:37 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:37.178: restoring session Oct 02 14:55:37 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:37.179: running Oct 02 14:55:37 rivo systemd[1]: Reloading. Oct 02 14:55:37 rivo volumio[3297]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Oct 02 14:55:37 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: appearance , registerThirdPartyUI Oct 02 14:55:37 rivo volumio[3297]: info: Registering Third Party UI: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 02 14:55:37 rivo volumio[3297]: info: UI already registered: {"uiName":"nova","uiPath":"/data/nova/current","uiPrettyName":"Nova"} Oct 02 14:55:37 rivo volumio[3297]: info: Nova UI: serving version 0.260928.0 Oct 02 14:55:37 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentUpdaterChannel Oct 02 14:55:37 rivo volumio[3297]: info: Nova UI: checking for updates on the stable channel: https://static.vlmapi.io/volumio/assets/v4/themes/nova/manifest.json Oct 02 14:55:37 rivo volumio[3297]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 02 14:55:37 rivo volumio[3297]: info: TidalConnect service stoped! Oct 02 14:55:37 rivo volumio[3297]: info: Asound.conf file unchanged, so no further update is needed Oct 02 14:55:37 rivo volumio[3297]: info: Output device has changed, restarting MPD Oct 02 14:55:37 rivo volumio[3297]: info: QobuzConnect: setDeactiveState invoked Oct 02 14:55:37 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:37 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:37 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:37 rivo sudo[3940]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 14:55:37 rivo sudo[3940]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:37 rivo sudo[3940]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:37 rivo sudo[3943]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 14:55:37 rivo sudo[3943]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:37 rivo sudo[3950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 02 14:55:37 rivo sudo[3950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:37 rivo volumio[3297]: info: MPD Permissions set Oct 02 14:55:38 rivo volumio[3297]: info: Turning Off Onboard LEDs Oct 02 14:55:38 rivo volumio[3297]: info: Successfully retrieved User Session From TIDAL Oct 02 14:55:38 rivo sudo[3962]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 02 14:55:38 rivo sudo[3962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:38 rivo sudo[3962]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:38 rivo sudo[3968]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 02 14:55:38 rivo sudo[3968]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:38 rivo volumio[3297]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 02 14:55:38 rivo volumio[3297]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 02 14:55:38 rivo sudo[3968]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo sudo[3971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 02 14:55:38 rivo sudo[3971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:38 rivo volumio[3297]: info: Nova UI: downloading version 0.261001.0 Oct 02 14:55:38 rivo volumio[3297]: info: Onboard LEDs initialized Oct 02 14:55:38 rivo volumio[3297]: info: Successfully retrieved User Subscription From TIDAL Oct 02 14:55:38 rivo launch-soloist.sh[3858]: 2026-10-02 14:55:38.650: logged in as 313g2xbrqzys4taupmmfrxcw4xpe Oct 02 14:55:38 rivo volumio[3297]: info: Successfully retrieved User Session From TIDAL Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:38 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:38 rivo sudo[3991]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=robo27,password=Batuser1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.1.196/docker/music /mnt/NAS/NAS Oct 02 14:55:38 rivo sudo[3991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:38 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:38 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:38 rivo volumio[3297]: info: Successfully retrieved User Subscription From TIDAL Oct 02 14:55:38 rivo volumio[3297]: info: Adding TIDAL REST API Endpoints Oct 02 14:55:39 rivo volumio[3297]: verbose: [squeezelite_mc] PlayerFinderChild process starting Oct 02 14:55:39 rivo volumio[3297]: verbose: [squeezelite_mc] PlayerFinderChild handling start request Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Server discovery started Oct 02 14:55:39 rivo volumio[3297]: verbose: [squeezelite_mc] PlayerFinder: child process started Oct 02 14:55:39 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:39 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:39 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S32_LE","SPECIAL","DSD_U32_BE"] Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"rivo","dsdFormat":"DSD_U32_BE","card":"5","mixerType":"None","mixer":null} Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 02 14:55:39 rivo sudo[4016]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 02 14:55:39 rivo sudo[4016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:39 rivo sudo[4016]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Oct 02 14:55:39 rivo sudo[4020]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Oct 02 14:55:39 rivo sudo[4020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:39 rivo kernel: Fine tdm clk setting range (0~2000000), 12287994 Oct 02 14:55:39 rivo kernel: Err: can not set rate to 4282967296! Oct 02 14:55:39 rivo kernel: Range[3920000 - 500000000] Oct 02 14:55:39 rivo kernel: out of value, fixed it Oct 02 14:55:39 rivo kernel: id=0 set inskew=0 Oct 02 14:55:39 rivo sudo[4020]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:39 rivo volumio[3297]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 02 14:55:39 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 02 14:55:40 rivo sudo[4024]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 02 14:55:40 rivo sudo[4024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:40 rivo sudo[4024]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:40 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Oct 02 14:55:40 rivo sudo[3906]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:40 rivo sudo[4042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 02 14:55:40 rivo sudo[4042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:40 rivo sudo[4047]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable --now volumio-flightmode.service volumio-flightmode.path Oct 02 14:55:40 rivo sudo[4047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:40 rivo sudo[3950]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:40 rivo sudo[4049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 02 14:55:40 rivo sudo[4049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:40 rivo systemd[1]: Reloading. Oct 02 14:55:40 rivo volumio[3297]: info: Nova UI: Nova 0.261001.0 activated, but it is not the active UI: not reloading clients Oct 02 14:55:40 rivo volumio[3297]: info: Nova UI: update check: version 0.261001.0 active Oct 02 14:55:41 rivo volumio-remote-updater[2920]: [2026-10-02 14:55:41] [connect] Successful connection Oct 02 14:55:41 rivo volumio-remote-updater[2920]: [2026-10-02 14:55:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1790945741 101 Oct 02 14:55:41 rivo mpd[3771]: 2026-10-02T14:55:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 14:55:41 rivo volumio[3297]: 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: 6 Oct 02 14:55:42 rivo volumio[3297]: info: SoloistConnect: connecting to ws://127.0.0.1:9878 Oct 02 14:55:42 rivo volumio[3297]: info: SoloistConnect: WebSocket connected Oct 02 14:55:42 rivo volumio[3297]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Queue Oct 02 14:55:42 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source Radio Paradise (RP2) Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source SoundCloud Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source QOBUZ Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source TIDAL Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source RADIO 357 Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source FIP Radio Oct 02 14:55:42 rivo volumio[3297]: Cannot find translation for source Randomizer Oct 02 14:55:42 rivo volumio[3297]: info: SoloistConnect: hold yield: is_active=false hold=2000ms Oct 02 14:55:42 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 02 14:55:43 rivo sudo[4042]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:43 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 02 14:55:43 rivo systemd[1]: Reloading. Oct 02 14:55:43 rivo kernel: fb: mem_free_work, free memory: addr:800000 Oct 02 14:55:44 rivo volumio[3297]: info: SoloistConnect: quality hold: fds=0 empty Oct 02 14:55:44 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:44 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Oct 02 14:55:44 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -113 Oct 02 14:55:45 rivo sudo[3991]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:45 rivo systemd[1]: systemd-udevd.service: bpf-firewall: Preparation of BPF allow maps failed: Invalid argument Oct 02 14:55:45 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Oct 02 14:55:45 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 02 14:55:45 rivo qobuz-connect[3740]: 20261002 14:55:45.595 [3740.3740] INFO SampleApp: Stopping Local configuration server Oct 02 14:55:45 rivo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 02 14:55:45 rivo volumio[3297]: info: SoloistConnect: open /data/soloist/cache/cache/6c/6c9271aed6a2490dedf7440266498f0a3cce1169.file 47382111 bytes over 370s = 1024 kbps -> Lossless Oct 02 14:55:45 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 02 14:55:45 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:45 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 02 14:55:45 rivo sudo[3676]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:45 rivo systemd[1]: Starting volumio-flightmode.service - Apply Volumio flight mode rfkill policy... Oct 02 14:55:45 rivo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 02 14:55:45 rivo sudo[3971]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:45 rivo volumio[3297]: error: FusionDsp - File size not found in array! Oct 02 14:55:45 rivo volumio[3297]: info: FusionDsp - Clipping test mode disabled, output will be sent to postDsp in camilla Oct 02 14:55:46 rivo volumio[3297]: info: camilladsp service started and running in background, instance 1 Oct 02 14:55:46 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 14:55:46 rivo qobuz-connect[3740]: 20261002 14:55:46.431 [3740.3740] INFO SampleApp: shat down connection on UNIX socket Oct 02 14:55:46 rivo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 02 14:55:46 rivo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 14:55:46 rivo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Oct 02 14:55:46 rivo sudo[4049]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp loaded Oct 02 14:55:46 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp - --[object Object] Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 02 14:55:46 rivo volumio[3297]: info: Adding Signal Path Element [object Object] Oct 02 14:55:46 rivo volumio[3297]: info: Adding fusiondspeq DSP Signal Path Element Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Oct 02 14:55:46 rivo volumio[3297]: info: FusionDsp - PEQ graph server listening on port 10015 Oct 02 14:55:46 rivo volumio[3297]: info: SoloistConnect: hold yield: is_active=false hold=2000ms Oct 02 14:55:46 rivo kernel: BT_RADIO going: off Oct 02 14:55:46 rivo kernel: AML_BT: going OFF Oct 02 14:55:46 rivo sudo[4286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 02 14:55:46 rivo sudo[4286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:46 rivo systemd[1]: Starting systemd-rfkill.service - Load/Save RF Kill Switch Status... Oct 02 14:55:46 rivo volumio[3297]: info: SoloistConnect: open /data/soloist/cache/cache/6c/6c9271aed6a2490dedf7440266498f0a3cce1169.file 47382111 bytes over 370s = 1024 kbps -> Lossless Oct 02 14:55:47 rivo volumio[3297]: info: Checking for updated MCU Firmware Oct 02 14:55:47 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 14:55:47 rivo systemd[1]: Started systemd-rfkill.service - Load/Save RF Kill Switch Status. Oct 02 14:55:47 rivo volumio[3297]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 02 14:55:47 rivo volumio[3297]: info: QobuzConnect: setDeactiveState invoked Oct 02 14:55:47 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:47 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:47 rivo volumio[3297]: info: Upmpdcli Daemon Started Oct 02 14:55:47 rivo volumio[3297]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Oct 02 14:55:47 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 02 14:55:47 rivo volumio[3297]: error: FusionDsp - Reload WebSocket error: [object Object] Oct 02 14:55:47 rivo volumio[3297]: warn: FusionDsp - CamillaDSP reload failed; continuing without rejecting the Promise to avoid an unhandled rejection: connect ECONNREFUSED 127.0.0.1:9876 Oct 02 14:55:47 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 02 14:55:47 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 02 14:55:47 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 02 14:55:47 rivo volumio[3297]: error: Upnp client error: Error: read ECONNRESET Oct 02 14:55:47 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 02 14:55:47 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 02 14:55:47 rivo volumio[3297]: info: Executing endpoint qc_getconfig Oct 02 14:55:47 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 02 14:55:47 rivo sudo[4321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 02 14:55:47 rivo sudo[4321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:47 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 02 14:55:47 rivo systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.471 [4276.4276] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.480 [4276.4276] INFO VolumeManager: [0xab392168]: Setting new playback volume: 75 Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.480 [4276.4276] INFO VolumeManager: [0xab392168]: Setting new mute state: 0 Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.480 [4276.4276] INFO AudioStreamManager: [0xab391ec0]: Setting new audio download buffer size: 1048576 Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.480 [4276.4276] INFO QobuzConnect: [0xab392a30]: Client initialized! Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.480 [4276.4276] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Oct 02 14:55:47 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.529 [4276.4276] INFO LocalConfigManager: [0xab391be8]: Starting Local Configuration server Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.530 [4276.4276] INFO SampleApp: Starting Local configuration server Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.531 [4276.4276] INFO SampleApp: Connected to UNIX socket client 0xab37c908 Oct 02 14:55:47 rivo volumio[3297]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 02 14:55:47 rivo volumio[3297]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 02 14:55:47 rivo qobuz-connect[4276]: 20261002 14:55:47.621 [4276.4276] INFO SampleApp: Playback volume changed: 75 Oct 02 14:55:47 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:47 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:47 rivo sudo[4321]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:47 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Oct 02 14:55:48 rivo systemd[1]: volumio-flightmode.service: Deactivated successfully. Oct 02 14:55:48 rivo systemd[1]: Finished volumio-flightmode.service - Apply Volumio flight mode rfkill policy. Oct 02 14:55:48 rivo sudo[4047]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:48 rivo sudo[4366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Oct 02 14:55:48 rivo sudo[4366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:48 rivo volumio[3297]: info: Executing endpoint tc_getconfig Oct 02 14:55:48 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 02 14:55:48 rivo vtcs[4215]: STARTING TidalConnect services, version: 1.6.1 Oct 02 14:55:48 rivo systemd[1]: Starting squeezelite.service - Squeezelite lightweight headless Squeezebox emulator... Oct 02 14:55:48 rivo systemd[1]: Started squeezelite.service - Squeezelite lightweight headless Squeezebox emulator. Oct 02 14:55:48 rivo sudo[4366]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:48 rivo vtcs[4215]: STARTED TidalConnect services. Oct 02 14:55:48 rivo volumio[3297]: info: Executing endpoint tc_connect Oct 02 14:55:48 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 02 14:55:48 rivo volumio[3297]: info: Connecting to TidalConnect Oct 02 14:55:48 rivo volumio[3297]: info: TidalConnect service started! Oct 02 14:55:48 rivo volumio[3297]: info: CoreCommandRouter::servicePushState Oct 02 14:55:48 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:48 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:48 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:49 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:49 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:49 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:49 rivo volumio[3297]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Oct 02 14:55:49 rivo volumio[3297]: info: CoreCommandRouter::servicePushState Oct 02 14:55:49 rivo volumio[3297]: info: CoreStateMachine::pushState Oct 02 14:55:49 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:49 rivo volumio[3297]: info: CoreCommandRouter::volumioPushState Oct 02 14:55:49 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:49 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:49 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:49 rivo volumio[3297]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received tidalconnect Oct 02 14:55:49 rivo volumio[3297]: info: FusionDsp - Volumio is not playing Oct 02 14:55:49 rivo volumio[3297]: info: FusionDsp - Clipped samples monitor stopped Oct 02 14:55:49 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 02 14:55:49 rivo sudo[4344]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 14:55:49 rivo sudo[4344]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 02 14:55:49 rivo sudo[4344]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:49 rivo sudo[4596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 02 14:55:49 rivo sudo[4596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:50 rivo sudo[4596]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:50 rivo volumio[3297]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: websocket Total Clients: 7 Oct 02 14:55:50 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 14:55:50 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 02 14:55:50 rivo sudo[4656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 02 14:55:50 rivo sudo[4656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:51 rivo sudo[4656]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:51 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 02 14:55:51 rivo sudo[4677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 02 14:55:51 rivo sudo[4677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:52 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 02 14:55:52 rivo sudo[4677]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:52 rivo volumio[3297]: info: CALLMETHOD: miscellanea artwork_companion getSettings [object Object] Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: artwork_companion , getSettings Oct 02 14:55:52 rivo sudo[4683]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=robo27,password=Batuser1,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.1.196/docker/music /mnt/NAS/NAS Oct 02 14:55:52 rivo sudo[4683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:52 rivo systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 02 14:55:52 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:52 rivo sudo[4286]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:52 rivo volumio[3297]: info: Received Get System Info Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 14:55:52 rivo volumio[3297]: info: Discovery: Getting this device information Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::volumioGetState Oct 02 14:55:52 rivo volumio[3297]: info: CorePlayQueue::getTrack 0 Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: alarm-clock , getSleep Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::volumioGetQueue Oct 02 14:55:52 rivo volumio[3297]: info: CoreStateMachine::getQueue Oct 02 14:55:52 rivo volumio[3297]: info: CorePlayQueue::getQueue Oct 02 14:55:52 rivo volumio[3297]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 14:55:52 rivo volumio[3297]: info: Listing playlists Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 02 14:55:53 rivo sudo[4709]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 02 14:55:53 rivo sudo[4709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo sudo[4715]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 14:55:53 rivo sudo[4715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo volumio[3297]: info: CALLMETHOD: miscellanea artwork_companion getSettings [object Object] Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: artwork_companion , getSettings Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: Error getting the SSID Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo sudo[4715]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo sudo[4721]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 14:55:53 rivo sudo[4721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo sudo[4725]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 14:55:53 rivo sudo[4725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: Error getting the SSID Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo sudo[4721]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo sudo[4725]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: Error getting the SSID Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo kernel: dhd_ioctl_entry: Interface is down Oct 02 14:55:53 rivo sudo[4731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 14:55:53 rivo sudo[4731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo volumio[3297]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 02 14:55:53 rivo sudo[4734]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 14:55:53 rivo sudo[4709]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo sudo[4734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo sudo[4731]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo sudo[4744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 02 14:55:53 rivo sudo[4734]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:53 rivo sudo[4744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 02 14:55:53 rivo volumio[3297]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 14:55:53 rivo volumio[3297]: info: CURURI: albums:// Oct 02 14:55:53 rivo volumio[3297]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:55:54 rivo sudo[4744]: pam_unix(sudo:session): session closed for user root Oct 02 14:55:54 rivo volumio[3297]: TypeError: Cannot read properties of undefined (reading 'sendCommand') Oct 02 14:55:54 rivo volumio[3297]: at /volumio/app/plugins/music_service/mpd/index.js:3000:22 Oct 02 14:55:54 rivo volumio[3297]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 02 14:55:54 rivo volumio[3297]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 14:55:54 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 14:55:54 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 14:55:57 rivo sudo[4760]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 14:54' Oct 02 14:55:57 rivo sudo[4760]: 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="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 18:35:21 UTC 2026" VOLUMIO_VERSION="4.209" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="d06564eaf7a6bebf8f928a4d13807037"