Oct 13 12:36:01 rivo kernel: IPVS: Creating netns size=1928 id=3 Oct 13 12:36:01 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 12:36:01 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 12:36:01 rivo systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 13 12:36:01 rivo systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 13 12:36:01 rivo bluetoothd[3469]: profiles/audio/bap.c:bap_adapter_probe() BAP requires ISO Socket which is not enabled Oct 13 12:36:01 rivo bluetoothd[3469]: bap: Operation not supported (95) Oct 13 12:36:01 rivo systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 13 12:36:01 rivo bluetoothd[3469]: Failed to Read Adv Monitor Features with status 0x01 Oct 13 12:36:01 rivo volumio[3254]: info: Plugin upnp_browser is not enabled Oct 13 12:36:01 rivo volumio[3254]: info: Plugin bluetooth is not enabled Oct 13 12:36:01 rivo volumio[3254]: info: Loading plugin "alarm-clock"... Oct 13 12:36:01 rivo systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 13 12:36:01 rivo systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 13 12:36:01 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:01 rivo ntpd[3181]: IO: Listen normally on 3 eth0 192.168.1.14:123 Oct 13 12:36:01 rivo ntpd[3181]: IO: new interface(s) found: waking up resolver Oct 13 12:36:01 rivo ntpd[3181]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 12:36:01 rivo ntpd[3181]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 13 12:36:01 rivo ntpd[3181]: DNS: Pool taking: 193.239.214.227 Oct 13 12:36:01 rivo ntpd[3181]: DNS: Pool taking: 194.177.210.54 Oct 13 12:36:01 rivo ntpd[3181]: DNS: Pool taking: 147.52.190.11 Oct 13 12:36:01 rivo ntpd[3181]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 13 12:36:01 rivo dhcpcd[2983]: eth0: leased 192.168.1.14 for 1814400 seconds Oct 13 12:36:01 rivo dhcpcd[2983]: eth0: adding route to 192.168.1.0/24 Oct 13 12:36:01 rivo dhcpcd[2983]: eth0: adding default route via 192.168.1.1 Oct 13 12:36:01 rivo winbindd[3467]: [2026/10/13 12:36:01.990580, 0] ../../source3/winbindd/winbindd_idmap.c:372(wb_parent_idmap_setup_lookupname_done) Oct 13 12:36:01 rivo winbindd[3467]: wb_parent_idmap_setup_lookupname_done: Lookup domain name 'RIVO' failed 'NT_STATUS_IO_TIMEOUT' Oct 13 12:36:02 rivo volumio[3254]: info: Loading plugin "manifestui"... Oct 13 12:36:02 rivo smbd[3501]: [2026/10/13 12:36:02.110231, 0] ../../source3/smbd/server.c:1741(main) Oct 13 12:36:02 rivo smbd[3501]: smbd version 4.17.12-Debian started. Oct 13 12:36:02 rivo smbd[3501]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Oct 13 12:36:02 rivo systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 13 12:36:02 rivo systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Oct 13 12:36:02 rivo systemd[1]: welcome.service: Deactivated successfully. Oct 13 12:36:02 rivo systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 13 12:36:02 rivo systemd[1]: Stopping welcome.service - Show a welcome message on console... Oct 13 12:36:02 rivo systemd[1]: Starting welcome.service - Show a welcome message on console... Oct 13 12:36:02 rivo welcome[3526]: Resolved ip:[1] 192.168.1.14 Oct 13 12:36:02 rivo systemd[1]: Finished welcome.service - Show a welcome message on console. Oct 13 12:36:02 rivo systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Oct 13 12:36:02 rivo systemd[1]: Started smbd.service - Samba SMB Daemon. Oct 13 12:36:02 rivo systemd[1]: Reached target multi-user.target - Multi-User System. Oct 13 12:36:02 rivo systemd[1]: Reached target graphical.target - Graphical Interface. Oct 13 12:36:02 rivo systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Oct 13 12:36:02 rivo systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 13 12:36:02 rivo systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Oct 13 12:36:02 rivo dbus-daemon[2934]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 13 12:36:02 rivo systemd[1]: Started systemd-hostnamed.service - Hostname Service. Oct 13 12:36:02 rivo volumio[3254]: info: Loading plugin "metavolumio"... Oct 13 12:36:02 rivo bluealsa[3478]: ../src/storage.c:90: Initializing persistent storage: /var/lib/bluealsa Oct 13 12:36:02 rivo bluealsa[3478]: main.c:663: Starting main dispatching loop Oct 13 12:36:02 rivo systemd[1]: Started bluealsa.service - BlueALSA service. Oct 13 12:36:02 rivo systemd[1]: Reached target bluetooth.target - Bluetooth Support. Oct 13 12:36:02 rivo systemd[1]: Startup finished in 13.638s (kernel) + 18.324s (userspace) = 31.962s. Oct 13 12:36:02 rivo bluealsa[3478]: main.c:118: Acquired D-Bus service name: org.bluealsa Oct 13 12:36:02 rivo bluealsa[3478]: bluealsa-dbus.c:358: Registering BlueALSA D-Bus manager: /org/bluealsa Oct 13 12:36:02 rivo bluealsa[3478]: bluez.c:153: Registering media application: /org/bluez/hci0 Oct 13 12:36:02 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/1 Oct 13 12:36:02 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/source/2 Oct 13 12:36:02 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/1 Oct 13 12:36:02 rivo bluealsa[3478]: bluez.c:743: Exporting media endpoint object: /org/bluez/hci0/A2DP/SBC/sink/2 Oct 13 12:36:02 rivo bluetoothd[3469]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 13 12:36:02 rivo bluetoothd[3469]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 13 12:36:02 rivo bluetoothd[3469]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 13 12:36:02 rivo bluetoothd[3469]: Endpoint registered: sender=:1.9 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 13 12:36:02 rivo ntpd[3181]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 12:36:02 rivo ntpd[3181]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 193.162.7.183 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 91.217.155.60 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 83.174.158.10 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 2a14:6700:106:3700:1a67:b0ff:fe3f:b712 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 2a14:6700:106:3700:bb42:c909:b846:f265 Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 2a06:5780:82:c0c::c0c Oct 13 12:36:02 rivo ntpd[3181]: DNS: Pool taking: 2001:678:3c8:1::226 Oct 13 12:36:02 rivo ntpd[3181]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 13 12:36:02 rivo winbindd[3475]: [2026/10/13 12:36:02.982555, 0] ../../source3/winbindd/winbindd_dual.c:162(child_write_response) Oct 13 12:36:02 rivo winbindd[3475]: Could not write result Oct 13 12:36:03 rivo ntpd[3181]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 12:36:03 rivo ntpd[3181]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 13 12:36:03 rivo ntpd[3181]: DNS: Pool taking: 193.93.164.194 Oct 13 12:36:03 rivo ntpd[3181]: DNS: Pool taking: 195.167.30.249 Oct 13 12:36:03 rivo ntpd[3181]: DNS: Pool taking: 162.159.200.123 Oct 13 12:36:03 rivo ntpd[3181]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 13 12:36:04 rivo volumio[3254]: info: Loading plugin "novaui"... Oct 13 12:36:04 rivo volumio[3254]: info: Applying required configuration parameters for plugin novaui Oct 13 12:36:04 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 1. Oct 13 12:36:04 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 12:36:04 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 12:36:04 rivo volumio[3254]: info: Plugin airplay_emulation is not enabled Oct 13 12:36:04 rivo volumio[3254]: info: Plugin cd_controller is not enabled Oct 13 12:36:04 rivo volumio[3254]: info: Loading plugin "last_100"... Oct 13 12:36:04 rivo volumio[3254]: info: Plugin qobuzconnect is not enabled Oct 13 12:36:04 rivo volumio[3254]: info: Plugin raat is not enabled Oct 13 12:36:04 rivo volumio[3254]: info: Loading plugin "streaming_services"... Oct 13 12:36:04 rivo upmpdcli[3556]: Could not open config: /tmp/upmpdcli.conf Oct 13 12:36:04 rivo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:04 rivo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Oct 13 12:36:04 rivo ntpd[3181]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 13 12:36:04 rivo ntpd[3181]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 13 12:36:04 rivo ntpd[3181]: DNS: Pool taking: 193.93.164.195 Oct 13 12:36:04 rivo ntpd[3181]: DNS: Pool taking: 185.201.254.21 Oct 13 12:36:04 rivo ntpd[3181]: DNS: Pool taking: 62.1.105.163 Oct 13 12:36:04 rivo ntpd[3181]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 13 12:36:05 rivo systemd[1]: systemd-rfkill.service: Deactivated successfully. Oct 13 12:36:06 rivo volumio[3254]: info: Plugin tidalconnect is not enabled Oct 13 12:36:06 rivo volumio[3254]: info: Loading plugin "webradio"... Oct 13 12:36:07 rivo volumio[3254]: info: Loading plugin "i2s_dacs"... Oct 13 12:36:07 rivo volumio[3254]: info: I2S DAC not set, start Auto-detection Oct 13 12:36:07 rivo volumio[3254]: info: Loading plugin "volumiodiscovery"... Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** For more information see Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:07 rivo volumio[3254]: *** WARNING *** For more information see Oct 13 12:36:07 rivo node[3254]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:07 rivo node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:07 rivo node[3254]: *** WARNING *** For more information see Oct 13 12:36:07 rivo node[3254]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:07 rivo node[3254]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:07 rivo node[3254]: *** WARNING *** For more information see Oct 13 12:36:07 rivo volumio[3254]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 12:36:07 rivo volumio[3254]: info: Discovery: Started advertising with name: Rivo Oct 13 12:36:07 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:07 rivo volumio[3254]: info: Plugin rp2 is not enabled Oct 13 12:36:07 rivo volumio[3254]: info: Plugin multiroom is not enabled Oct 13 12:36:07 rivo volumio[3254]: info: Loading plugin "outputs"... Oct 13 12:36:07 rivo volumio[3254]: info: Loading plugin "albumart"... Oct 13 12:36:07 rivo volumio[3254]: info: Plugin example_plugin is not enabled Oct 13 12:36:07 rivo volumio[3254]: info: Loading plugin "hi_res_audio"... Oct 13 12:36:07 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:07] [connect] Successful connection Oct 13 12:36:08 rivo ntpd[3181]: CLOCK: time stepped by 0.935337 Oct 13 12:36:08 rivo ntpd[3181]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Oct 13 12:36:08 rivo volumio[3560]: Forking 3 albumart workers Oct 13 12:36:10 rivo volumio[3568]: Starting albumart workers Oct 13 12:36:10 rivo volumio[3254]: info: Applying required configuration parameters for plugin hi_res_audio Oct 13 12:36:10 rivo volumio[3254]: info: Loading plugin "inputs"... Oct 13 12:36:10 rivo volumio[3569]: Starting albumart workers Oct 13 12:36:10 rivo volumio[3567]: Starting albumart workers Oct 13 12:36:11 rivo volumio[3254]: info: Loading plugin "qobuz"... Oct 13 12:36:12 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:12 rivo volumio[3254]: info: Plugin smart_inputs is not enabled Oct 13 12:36:12 rivo volumio[3254]: info: Loading plugin "tidal"... Oct 13 12:36:14 rivo volumio[3254]: info: Loading plugin "rivocontrol"... Oct 13 12:36:14 rivo volumio[3254]: info: Adding this device properties Oct 13 12:36:14 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 13 12:36:14 rivo volumio[3254]: info: Setting Additional Device Volumio Properties: [object Object] Oct 13 12:36:14 rivo volumio[3254]: info: Loading plugin "updater_comm"... Oct 13 12:36:14 rivo volumio[3254]: info: Loading plugin "cec_controller"... Oct 13 12:36:15 rivo volumio[3254]: info: Applying required configuration parameters for plugin cec_controller Oct 13 12:36:15 rivo volumio[3254]: info: Plugin mpdemulation is not enabled Oct 13 12:36:15 rivo volumio[3254]: info: Loading plugin "rest_api"... Oct 13 12:36:15 rivo volumio[3254]: info: Loading plugin "websocket"... Oct 13 12:36:15 rivo volumio[3254]: info: Starting Socket.io Server version 1.7.4 Oct 13 12:36:15 rivo volumio[3254]: info: Plugin fusiondsp is not enabled Oct 13 12:36:15 rivo volumio[3254]: info: Loading i18n strings for locale en Oct 13 12:36:15 rivo volumio[3254]: Updating browse sources language Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::initPlayerControls Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:15 rivo volumio[3254]: Express server listening on port 3000 Oct 13 12:36:15 rivo volumio[3254]: [Metrics] WebUI: 19s 857.72ms Oct 13 12:36:15 rivo volumio[3254]: info: CoreStateMachine::resetVolumioState Oct 13 12:36:15 rivo volumio[3254]: info: CoreStateMachine::getcurrentVolume Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 12:36:15 rivo volumio[3254]: info: CoreStateMachine::pushState Oct 13 12:36:15 rivo volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:15 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:36:15 rivo sudo[3635]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:15 rivo sudo[3635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:15 rivo volumio[3254]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 12:36:15 rivo volumio[3254]: info: Completed loading Core Plugins Oct 13 12:36:15 rivo sudo[3637]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:15 rivo sudo[3637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:15 rivo volumio[3254]: info: Preparing to generate the ALSA configuration file Oct 13 12:36:15 rivo sudo[3635]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:15 rivo sudo[3637]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:15 rivo volumio[3254]: info: Volumio Network Manager: Network status updated: 0 Oct 13 12:36:15 rivo volumio[3254]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 12:36:16 rivo volumio[3254]: verbose: New Socket.io Connection to rivo.local from 192.168.1.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/27.0.1 Safari/605.1.15 Engine version: 3 Transport: websocket Total Clients: 1 Oct 13 12:36:16 rivo volumio[3254]: info: Reloading queue from file Oct 13 12:36:16 rivo volumio[3254]: info: CoreStateMachine::setRepeat null single undefined Oct 13 12:36:16 rivo volumio[3254]: info: CoreStateMachine::pushState Oct 13 12:36:16 rivo volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:16 rivo volumio[3254]: info: CoreStateMachine::setRandom null Oct 13 12:36:16 rivo volumio[3254]: info: CoreStateMachine::pushState Oct 13 12:36:16 rivo volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:16 rivo volumio[3254]: verbose: New Socket.io Connection to rivo.local from 192.168.1.31 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/27.0.1 Safari/605.1.15 Engine version: 3 Transport: websocket Total Clients: 2 Oct 13 12:36:16 rivo volumio[3254]: info: Asound.conf file unchanged, so no further update is needed Oct 13 12:36:16 rivo volumio[3254]: info: Output device has changed, restarting MPD Oct 13 12:36:16 rivo volumio[3254]: info: ___________ START PLUGINS ___________ Oct 13 12:36:16 rivo sudo[3649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:36:16 rivo sudo[3649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3649]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:36:16 rivo sudo[3651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo volumio[3254]: info: ControllerMpd::onStart: Initializing MPD Oct 13 12:36:16 rivo volumio[3254]: info: Creating MPD Configuration file Oct 13 12:36:16 rivo sudo[3654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 13 12:36:16 rivo sudo[3654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo volumio[3254]: info: Adding METAVOLUMIO REST API Endpoints Oct 13 12:36:16 rivo volumio[3254]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:16 rivo volumio[3254]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:16 rivo volumio[3254]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:16 rivo volumio[3254]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:16 rivo volumio[3254]: info: Nova UI: unpacking bundled version 0.260915.4 Oct 13 12:36:16 rivo sudo[3657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:36:16 rivo sudo[3657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3657]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:16 rivo volumio[3254]: info: [1791884176211] CoreMusicLibrary::Adding element Last_100 Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:16 rivo volumio[3254]: info: Streaming services startup Oct 13 12:36:16 rivo volumio[3254]: info: Starting Streaming Daemon Oct 13 12:36:16 rivo sudo[3660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:36:16 rivo sudo[3660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:16 rivo volumio[3254]: info: [1791884176267] CoreMusicLibrary::Adding element Webradio Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:16 rivo sudo[3665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 12:36:16 rivo sudo[3665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:16 rivo volumio[3254]: info: Initializing BBC Radios Oct 13 12:36:16 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 12:36:16 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 12:36:16 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:16 rivo sudo[3654]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3665]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:36:16 rivo volumio[3254]: error: Hi Res Audio Failed Login: Missing Login Data Oct 13 12:36:16 rivo volumio[3254]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 13 12:36:16 rivo volumio[3254]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 13 12:36:16 rivo volumio[3254]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 13 12:36:16 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 13 12:36:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Oct 13 12:36:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Oct 13 12:36:16 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Oct 13 12:36:16 rivo volumio[3254]: info: Touch Event Listener Process Starting Oct 13 12:36:16 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 12:36:16 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 13 12:36:16 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 12:36:16 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 12:36:16 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 12:36:16 rivo volumio[3254]: info: Adding TIDAL to Browse Sources Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:16 rivo volumio[3254]: info: [1791884176430] CoreMusicLibrary::Adding element TIDAL Oct 13 12:36:16 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:16 rivo volumio[3254]: Cannot find translation for source TIDAL Oct 13 12:36:16 rivo volumio[3254]: info: Refreshing TIDAL token Oct 13 12:36:16 rivo sudo[3697]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 13 12:36:16 rivo sudo[3697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 12:36:16 rivo sudo[3697]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3712]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 13 12:36:16 rivo sudo[3712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3712]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3715]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 13 12:36:16 rivo sudo[3715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3715]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3718]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 13 12:36:16 rivo sudo[3718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3718]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3721]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 13 12:36:16 rivo sudo[3721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3721]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo volumio[3254]: info: Apply VIM3L Onboard LEDs Settings Oct 13 12:36:16 rivo volumio[3254]: info: Turning On Onboard LEDs Oct 13 12:36:16 rivo volumio[3254]: info: Starting CEC Event listener Oct 13 12:36:16 rivo volumio[3254]: info: Volumio Calling Home Oct 13 12:36:16 rivo sudo[3725]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 13 12:36:16 rivo sudo[3725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo sudo[3725]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo sudo[3730]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 13 12:36:16 rivo sudo[3730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:16 rivo volumio5-onboarding[3677]: time=2026-10-13T12:36:16.835+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:16 rivo sudo[3730]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:16 rivo volumio[3254]: info: Stopping AccessToken refresher cron for QOBUZ Oct 13 12:36:16 rivo volumio[3254]: info: AccessToken refresher cron started for QOBUZ Oct 13 12:36:16 rivo sudo[3699]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 12:36:16 rivo sudo[3699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 12:36:16 rivo volumio[3254]: info: Adding QOBUZ REST API Endpoints Oct 13 12:36:17 rivo sudo[3737]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Oct 13 12:36:17 rivo sudo[3699]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:17 rivo volumio[3254]: info: Received Get System Version Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:17 rivo volumio[3254]: info: Received Get System Info Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:36:17 rivo volumio[3254]: info: Discovery: Getting this device information Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::volumioGetState Oct 13 12:36:17 rivo volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 13 12:36:17 rivo sudo[3743]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 13 12:36:17 rivo sudo[3743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo sudo[3748]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:17 rivo sudo[3748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo sudo[3756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:17 rivo sudo[3756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo volumio[3254]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: Error getting the SSID Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 13 12:36:17 rivo sudo[3748]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 12:36:17 rivo sudo[3756]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: Error getting the SSID Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::volumioGetState Oct 13 12:36:17 rivo volumio[3254]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:36:17 rivo volumio[3254]: info: CoreStateMachine::getQueue Oct 13 12:36:17 rivo volumio[3254]: info: CorePlayQueue::getQueue Oct 13 12:36:17 rivo volumio[3254]: Searching all installed plugins Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:17 rivo sudo[3743]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: , search Oct 13 12:36:17 rivo volumio[3254]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 12:36:17 rivo sudo[3766]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:17 rivo volumio[3254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:17 rivo sudo[3766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo sudo[3769]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:17 rivo sudo[3769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo sudo[3761]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:17 rivo sudo[3761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:17 rivo sudo[3766]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo volumio[3254]: TypeError: Cannot read properties of undefined (reading 'then') Oct 13 12:36:17 rivo volumio[3254]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1596:17) Oct 13 12:36:17 rivo volumio[3254]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 13 12:36:17 rivo volumio[3254]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 13 12:36:17 rivo volumio[3254]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 13 12:36:17 rivo volumio[3254]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Oct 13 12:36:17 rivo volumio[3254]: at Socket.emit (node:events:514:28) Oct 13 12:36:17 rivo volumio[3254]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 12:36:17 rivo volumio[3254]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 13 12:36:17 rivo volumio[3254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:17 rivo sudo[3769]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo sudo[3761]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: Error getting the SSID Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:17 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:18 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:18 rivo sudo[3786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 12:35' Oct 13 12:36:18 rivo sudo[3786]: 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"