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) Oct 13 12:36:18 rivo sudo[3786]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:19 rivo volumio5-onboarding[3677]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:42892->127.0.0.1:3000: read: connection reset by peer Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:19 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:19 rivo sudo[3660]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:19 rivo sudo[3651]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:19 rivo systemd[1]: systemd-fsckd.service: Deactivated successfully. Oct 13 12:36:19 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 13 12:36:19 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:19 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:19 rivo volumio5-onboarding[3794]: time=2026-10-13T12:36:19.259+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:19 rivo volumio5-onboarding[3794]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:19 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 13 12:36:19 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:19 rivo systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 13 12:36:19 rivo systemd[1]: Started volumio.service - Volumio Backend Module. Oct 13 12:36:19 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 13 12:36:19 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo volumio5-onboarding[3819]: time=2026-10-13T12:36:19.465+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:19 rivo volumio5-onboarding[3819]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 13 12:36:19 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:19 rivo volumio5-onboarding[3828]: time=2026-10-13T12:36:19.832+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:19 rivo volumio5-onboarding[3828]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:19 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 13 12:36:20 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:20 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:20 rivo volumio5-onboarding[3836]: time=2026-10-13T12:36:20.084+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:20 rivo volumio5-onboarding[3836]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 13 12:36:20 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Start request repeated too quickly. Oct 13 12:36:20 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:20 rivo systemd[1]: Failed to start volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:20 rivo systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Oct 13 12:36:20 rivo systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 12:36:20 rivo systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Oct 13 12:36:21 rivo volumio[3808]: info: ------------------------------------------- Oct 13 12:36:21 rivo volumio[3808]: info: ----- Volumio3 ---- Oct 13 12:36:21 rivo volumio[3808]: info: ------------------------------------------- Oct 13 12:36:21 rivo volumio[3808]: info: ----- System startup ---- Oct 13 12:36:21 rivo volumio[3808]: info: ------------------------------------------- Oct 13 12:36:21 rivo mpd[3741]: 2026-10-13T12:36:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 12:36:22 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 12:36:22 rivo volumio[3808]: info: MYVOLUMIO Environment detected Oct 13 12:36:22 rivo volumio[3808]: info: Plugin folders cleanup Oct 13 12:36:22 rivo volumio[3808]: info: Scanning into folder /volumio/app/plugins/ Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category audio_interface Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category miscellanea Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category music_service Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category plugins.json Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category system_controller Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category user_interface Oct 13 12:36:22 rivo volumio[3808]: info: Scanning into folder /data/plugins/ Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category audio_interface Oct 13 12:36:22 rivo volumio[3808]: info: Scanning category music_service Oct 13 12:36:22 rivo volumio[3808]: info: Plugin folders cleanup completed Oct 13 12:36:22 rivo volumio[3808]: info: ------------------------------------------- Oct 13 12:36:22 rivo volumio[3808]: info: ----- Core plugins startup ---- Oct 13 12:36:22 rivo volumio[3808]: info: ------------------------------------------- Oct 13 12:36:22 rivo volumio[3808]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin upnp to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin raat to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 12:36:22 rivo volumio[3808]: info: Loading plugins from folder /data/plugins/ Oct 13 12:36:22 rivo volumio[3808]: info: Loading plugin "system"... Oct 13 12:36:22 rivo volumio[3808]: info: Loading plugin "appearance"... Oct 13 12:36:23 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:23] [connect] Successful connection Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "network"... Oct 13 12:36:23 rivo volumio[3808]: info: Refreshing Cached IP Addresses Oct 13 12:36:23 rivo sudo[3871]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:23 rivo sudo[3871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "services"... Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "volumio5onboarding"... Oct 13 12:36:23 rivo sudo[3873]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:23 rivo sudo[3873]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:23 rivo sudo[3871]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "alsa_controller"... Oct 13 12:36:23 rivo sudo[3873]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:23 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "wizard"... Oct 13 12:36:23 rivo volumio[3808]: info: Loading plugin "networkfs"... Oct 13 12:36:23 rivo volumio[3808]: info: Starting Udev Watcher for removable devices Oct 13 12:36:24 rivo volumio[3808]: info: Mounting Device EFI Oct 13 12:36:24 rivo sudo[3902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:24 rivo sudo[3902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:24 rivo sudo[3902]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:24 rivo volumio[3808]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:24 rivo volumio[3808]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:24 rivo volumio[3808]: error: Failed to mount EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:24 rivo volumio[3808]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:24 rivo volumio[3808]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:24 rivo volumio[3808]: info: Mounting Device CORSAIR2 Oct 13 12:36:24 rivo sudo[3908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/CORSAIR2 -o noatime Oct 13 12:36:24 rivo sudo[3908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:24 rivo sudo[3908]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:24 rivo volumio[3808]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:24 rivo volumio[3808]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:24 rivo volumio[3808]: error: Failed to mount CORSAIR2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/CORSAIR2" -o noatime Oct 13 12:36:24 rivo volumio[3808]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:24 rivo volumio[3808]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:24 rivo volumio[3808]: info: Ignoring mount for partition: BOOT Oct 13 12:36:24 rivo volumio[3808]: info: Ignoring mount for partition: volumio Oct 13 12:36:24 rivo volumio[3808]: info: Ignoring mount for partition: volumio_data Oct 13 12:36:24 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:24 rivo volumio[3808]: info: Loading plugin "volumio_command_line_client"... Oct 13 12:36:24 rivo volumio[3808]: info: Loading plugin "upnp"... Oct 13 12:36:24 rivo volumio[3808]: info: [1791884184737] Starting Upmpd Daemon Oct 13 12:36:24 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:24 rivo volumio[3808]: info: Loading plugin "my_music"... Oct 13 12:36:24 rivo volumio[3808]: info: Loading plugin "mpd"... Oct 13 12:36:25 rivo volumio[3808]: info: Plugin upnp_browser is not enabled Oct 13 12:36:25 rivo volumio[3808]: info: Plugin bluetooth is not enabled Oct 13 12:36:25 rivo volumio[3808]: info: Loading plugin "alarm-clock"... Oct 13 12:36:25 rivo volumio[3808]: info: Loading plugin "manifestui"... Oct 13 12:36:25 rivo volumio[3808]: info: Loading plugin "metavolumio"... Oct 13 12:36:26 rivo volumio[3808]: info: Loading plugin "novaui"... Oct 13 12:36:26 rivo volumio[3808]: info: Applying required configuration parameters for plugin novaui Oct 13 12:36:26 rivo volumio[3808]: info: Plugin airplay_emulation is not enabled Oct 13 12:36:26 rivo volumio[3808]: info: Plugin cd_controller is not enabled Oct 13 12:36:26 rivo volumio[3808]: info: Loading plugin "last_100"... Oct 13 12:36:26 rivo volumio[3808]: info: Plugin qobuzconnect is not enabled Oct 13 12:36:26 rivo volumio[3808]: info: Plugin raat is not enabled Oct 13 12:36:26 rivo volumio[3808]: info: Loading plugin "streaming_services"... Oct 13 12:36:27 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:27 rivo volumio[3808]: info: Plugin tidalconnect is not enabled Oct 13 12:36:27 rivo volumio[3808]: info: Loading plugin "webradio"... Oct 13 12:36:28 rivo volumio[3808]: info: Loading plugin "i2s_dacs"... Oct 13 12:36:28 rivo volumio[3808]: info: I2S DAC not set, start Auto-detection Oct 13 12:36:28 rivo volumio[3808]: info: Loading plugin "volumiodiscovery"... Oct 13 12:36:29 rivo volumio[3808]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:29 rivo volumio[3808]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:29 rivo volumio[3808]: *** WARNING *** For more information see Oct 13 12:36:29 rivo volumio[3808]: *** 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:29 rivo volumio[3808]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:29 rivo node[3808]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:29 rivo volumio[3808]: *** WARNING *** For more information see Oct 13 12:36:29 rivo node[3808]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:29 rivo node[3808]: *** WARNING *** For more information see Oct 13 12:36:29 rivo node[3808]: *** 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:29 rivo node[3808]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:29 rivo node[3808]: *** WARNING *** For more information see Oct 13 12:36:29 rivo volumio[3808]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 12:36:29 rivo volumio[3808]: info: Discovery: Started advertising with name: Rivo Oct 13 12:36:29 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:29 rivo volumio[3808]: info: Plugin rp2 is not enabled Oct 13 12:36:29 rivo volumio[3808]: info: Plugin multiroom is not enabled Oct 13 12:36:29 rivo volumio[3808]: info: Loading plugin "outputs"... Oct 13 12:36:29 rivo volumio[3808]: info: Loading plugin "albumart"... Oct 13 12:36:29 rivo volumio[3808]: info: Plugin example_plugin is not enabled Oct 13 12:36:29 rivo volumio[3808]: info: Loading plugin "hi_res_audio"... Oct 13 12:36:29 rivo volumio[3915]: Forking 3 albumart workers Oct 13 12:36:30 rivo volumio[3808]: info: Applying required configuration parameters for plugin hi_res_audio Oct 13 12:36:30 rivo volumio[3808]: info: Loading plugin "inputs"... Oct 13 12:36:31 rivo volumio[3923]: Starting albumart workers Oct 13 12:36:31 rivo volumio[3924]: Starting albumart workers Oct 13 12:36:31 rivo volumio[3922]: Starting albumart workers Oct 13 12:36:31 rivo volumio[3808]: info: Loading plugin "qobuz"... Oct 13 12:36:31 rivo systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 13 12:36:32 rivo volumio[3808]: info: Plugin smart_inputs is not enabled Oct 13 12:36:32 rivo volumio[3808]: info: Loading plugin "tidal"... Oct 13 12:36:33 rivo systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 13 12:36:33 rivo systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 13 12:36:33 rivo volumio[3808]: info: Loading plugin "rivocontrol"... Oct 13 12:36:33 rivo systemd[1]: systemd-hostnamed.service: Deactivated successfully. Oct 13 12:36:33 rivo volumio[3808]: info: Adding this device properties Oct 13 12:36:33 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 13 12:36:33 rivo volumio[3808]: info: Setting Additional Device Volumio Properties: [object Object] Oct 13 12:36:33 rivo volumio[3808]: info: Loading plugin "updater_comm"... Oct 13 12:36:33 rivo volumio[3808]: info: Loading plugin "cec_controller"... Oct 13 12:36:34 rivo kernel: fb: mem_free_work, free memory: addr:800000 Oct 13 12:36:34 rivo volumio[3808]: info: Applying required configuration parameters for plugin cec_controller Oct 13 12:36:34 rivo volumio[3808]: info: Plugin mpdemulation is not enabled Oct 13 12:36:34 rivo volumio[3808]: info: Loading plugin "rest_api"... Oct 13 12:36:34 rivo volumio[3808]: info: Loading plugin "websocket"... Oct 13 12:36:34 rivo volumio[3808]: info: Starting Socket.io Server version 1.7.4 Oct 13 12:36:34 rivo volumio[3808]: info: Plugin fusiondsp is not enabled Oct 13 12:36:34 rivo volumio[3808]: info: Loading i18n strings for locale en Oct 13 12:36:34 rivo volumio[3808]: Updating browse sources language Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::initPlayerControls Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: Express server listening on port 3000 Oct 13 12:36:34 rivo volumio[3808]: [Metrics] WebUI: 13s 392.71ms Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::resetVolumioState Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::getcurrentVolume Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::pushState Oct 13 12:36:34 rivo volumio[3808]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:36:34 rivo volumio[3808]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 12:36:34 rivo volumio[3808]: info: Completed loading Core Plugins Oct 13 12:36:34 rivo volumio[3808]: info: Preparing to generate the ALSA configuration file Oct 13 12:36:34 rivo volumio[3808]: info: Volumio Network Manager: Network status updated: 1 Oct 13 12:36:34 rivo volumio[3808]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 12:36:34 rivo volumio[3808]: 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:34 rivo volumio[3808]: info: Reloading queue from file Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::setRepeat null single undefined Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::pushState Oct 13 12:36:34 rivo volumio[3808]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::setRandom null Oct 13 12:36:34 rivo volumio[3808]: info: CoreStateMachine::pushState Oct 13 12:36:34 rivo volumio[3808]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:34 rivo volumio[3808]: Searching plugin music_service/webradio Oct 13 12:36:34 rivo volumio[3808]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 13 12:36:34 rivo volumio[3808]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:34 rivo volumio[3808]: TypeError: Cannot read properties of undefined (reading 'search') Oct 13 12:36:34 rivo volumio[3808]: at ControllerWebradio.searchWithTuneIn (/volumio/app/plugins/music_service/webradio/index.js:1009:34) Oct 13 12:36:34 rivo volumio[3808]: at ControllerWebradio.search (/volumio/app/plugins/music_service/webradio/index.js:866:26) Oct 13 12:36:34 rivo volumio[3808]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 13 12:36:34 rivo volumio[3808]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Oct 13 12:36:34 rivo volumio[3808]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Oct 13 12:36:34 rivo volumio[3808]: at Socket.emit (node:events:514:28) Oct 13 12:36:34 rivo volumio[3808]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 12:36:34 rivo volumio[3808]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 13 12:36:34 rivo volumio[3808]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:35 rivo sudo[4039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 12:35' Oct 13 12:36:35 rivo sudo[4039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:35 rivo sudo[4039]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:36 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:36 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 13 12:36:36 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:36 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:36 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 13 12:36:36 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:36 rivo systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 13 12:36:36 rivo systemd[1]: Started volumio.service - Volumio Backend Module. Oct 13 12:36:36 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:37 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:38 rivo volumio[4053]: info: ------------------------------------------- Oct 13 12:36:38 rivo volumio[4053]: info: ----- Volumio3 ---- Oct 13 12:36:38 rivo volumio[4053]: info: ------------------------------------------- Oct 13 12:36:38 rivo volumio[4053]: info: ----- System startup ---- Oct 13 12:36:38 rivo volumio[4053]: info: ------------------------------------------- Oct 13 12:36:38 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:38] [connect] Successful connection Oct 13 12:36:39 rivo volumio[4053]: info: MYVOLUMIO Environment detected Oct 13 12:36:39 rivo volumio[4053]: info: Plugin folders cleanup Oct 13 12:36:39 rivo volumio[4053]: info: Scanning into folder /volumio/app/plugins/ Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category audio_interface Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category miscellanea Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category music_service Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category plugins.json Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category system_controller Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category user_interface Oct 13 12:36:39 rivo volumio[4053]: info: Scanning into folder /data/plugins/ Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category audio_interface Oct 13 12:36:39 rivo volumio[4053]: info: Scanning category music_service Oct 13 12:36:39 rivo volumio[4053]: info: Plugin folders cleanup completed Oct 13 12:36:39 rivo volumio[4053]: info: ------------------------------------------- Oct 13 12:36:39 rivo volumio[4053]: info: ----- Core plugins startup ---- Oct 13 12:36:39 rivo volumio[4053]: info: ------------------------------------------- Oct 13 12:36:39 rivo volumio[4053]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin upnp to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin raat to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 12:36:39 rivo volumio[4053]: info: Loading plugins from folder /data/plugins/ Oct 13 12:36:39 rivo volumio[4053]: info: Loading plugin "system"... Oct 13 12:36:39 rivo volumio[4053]: info: Loading plugin "appearance"... Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "network"... Oct 13 12:36:40 rivo volumio[4053]: info: Refreshing Cached IP Addresses Oct 13 12:36:40 rivo sudo[4081]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:40 rivo sudo[4081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "services"... Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "volumio5onboarding"... Oct 13 12:36:40 rivo sudo[4083]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:40 rivo sudo[4083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:40 rivo sudo[4081]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "alsa_controller"... Oct 13 12:36:40 rivo sudo[4083]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:40 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "wizard"... Oct 13 12:36:40 rivo volumio[4053]: info: Loading plugin "networkfs"... Oct 13 12:36:41 rivo volumio[4053]: info: Starting Udev Watcher for removable devices Oct 13 12:36:41 rivo volumio[4053]: info: Mounting Device EFI Oct 13 12:36:41 rivo sudo[4112]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:41 rivo sudo[4112]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:41 rivo sudo[4112]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:41 rivo volumio[4053]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:41 rivo volumio[4053]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:41 rivo volumio[4053]: error: Failed to mount EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:41 rivo volumio[4053]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:41 rivo volumio[4053]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:41 rivo volumio[4053]: info: Mounting Device CORSAIR2 Oct 13 12:36:41 rivo sudo[4118]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/CORSAIR2 -o noatime Oct 13 12:36:41 rivo sudo[4118]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:41 rivo sudo[4118]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:41 rivo volumio[4053]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:41 rivo volumio[4053]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:41 rivo volumio[4053]: error: Failed to mount CORSAIR2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/CORSAIR2" -o noatime Oct 13 12:36:41 rivo volumio[4053]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:41 rivo volumio[4053]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:41 rivo volumio[4053]: info: Ignoring mount for partition: BOOT Oct 13 12:36:41 rivo volumio[4053]: info: Ignoring mount for partition: volumio Oct 13 12:36:41 rivo volumio[4053]: info: Ignoring mount for partition: volumio_data Oct 13 12:36:41 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "volumio_command_line_client"... Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "upnp"... Oct 13 12:36:41 rivo volumio[4053]: info: [1791884201275] Starting Upmpd Daemon Oct 13 12:36:41 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "my_music"... Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "mpd"... Oct 13 12:36:41 rivo volumio[4053]: info: Plugin upnp_browser is not enabled Oct 13 12:36:41 rivo volumio[4053]: info: Plugin bluetooth is not enabled Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "alarm-clock"... Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "manifestui"... Oct 13 12:36:41 rivo volumio[4053]: info: Loading plugin "metavolumio"... Oct 13 12:36:43 rivo volumio[4053]: info: Loading plugin "novaui"... Oct 13 12:36:43 rivo volumio[4053]: info: Applying required configuration parameters for plugin novaui Oct 13 12:36:43 rivo volumio[4053]: info: Plugin airplay_emulation is not enabled Oct 13 12:36:43 rivo volumio[4053]: info: Plugin cd_controller is not enabled Oct 13 12:36:43 rivo volumio[4053]: info: Loading plugin "last_100"... Oct 13 12:36:43 rivo volumio[4053]: info: Plugin qobuzconnect is not enabled Oct 13 12:36:43 rivo volumio[4053]: info: Plugin raat is not enabled Oct 13 12:36:43 rivo volumio[4053]: info: Loading plugin "streaming_services"... Oct 13 12:36:44 rivo volumio[4053]: info: Plugin tidalconnect is not enabled Oct 13 12:36:44 rivo volumio[4053]: info: Loading plugin "webradio"... Oct 13 12:36:45 rivo volumio[4053]: info: Loading plugin "i2s_dacs"... Oct 13 12:36:45 rivo volumio[4053]: info: I2S DAC not set, start Auto-detection Oct 13 12:36:45 rivo volumio[4053]: info: Loading plugin "volumiodiscovery"... Oct 13 12:36:45 rivo volumio[4053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:45 rivo volumio[4053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:45 rivo volumio[4053]: *** WARNING *** For more information see Oct 13 12:36:45 rivo volumio[4053]: *** 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:45 rivo volumio[4053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:45 rivo node[4053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:36:45 rivo volumio[4053]: *** WARNING *** For more information see Oct 13 12:36:45 rivo node[4053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:45 rivo node[4053]: *** WARNING *** For more information see Oct 13 12:36:45 rivo node[4053]: *** 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:45 rivo node[4053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:36:45 rivo volumio[4053]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 12:36:45 rivo node[4053]: *** WARNING *** For more information see Oct 13 12:36:45 rivo volumio[4053]: info: Discovery: Started advertising with name: Rivo Oct 13 12:36:45 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:45 rivo volumio[4053]: info: Plugin rp2 is not enabled Oct 13 12:36:45 rivo volumio[4053]: info: Plugin multiroom is not enabled Oct 13 12:36:45 rivo volumio[4053]: info: Loading plugin "outputs"... Oct 13 12:36:45 rivo volumio[4053]: info: Loading plugin "albumart"... Oct 13 12:36:45 rivo volumio[4053]: info: Plugin example_plugin is not enabled Oct 13 12:36:45 rivo volumio[4053]: info: Loading plugin "hi_res_audio"... Oct 13 12:36:46 rivo volumio[4125]: Forking 3 albumart workers Oct 13 12:36:47 rivo volumio[4053]: info: Applying required configuration parameters for plugin hi_res_audio Oct 13 12:36:47 rivo volumio[4053]: info: Loading plugin "inputs"... Oct 13 12:36:47 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:47 rivo volumio[4132]: Starting albumart workers Oct 13 12:36:47 rivo volumio[4133]: Starting albumart workers Oct 13 12:36:47 rivo volumio[4134]: Starting albumart workers Oct 13 12:36:47 rivo volumio[4053]: info: Loading plugin "qobuz"... Oct 13 12:36:48 rivo volumio[4053]: info: Plugin smart_inputs is not enabled Oct 13 12:36:48 rivo volumio[4053]: info: Loading plugin "tidal"... Oct 13 12:36:49 rivo volumio[4053]: info: Loading plugin "rivocontrol"... Oct 13 12:36:50 rivo volumio[4053]: info: Adding this device properties Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 13 12:36:50 rivo volumio[4053]: info: Setting Additional Device Volumio Properties: [object Object] Oct 13 12:36:50 rivo volumio[4053]: info: Loading plugin "updater_comm"... Oct 13 12:36:50 rivo volumio[4053]: info: Loading plugin "cec_controller"... Oct 13 12:36:50 rivo volumio[4053]: info: Applying required configuration parameters for plugin cec_controller Oct 13 12:36:50 rivo volumio[4053]: info: Plugin mpdemulation is not enabled Oct 13 12:36:50 rivo volumio[4053]: info: Loading plugin "rest_api"... Oct 13 12:36:50 rivo volumio[4053]: info: Loading plugin "websocket"... Oct 13 12:36:50 rivo volumio[4053]: info: Starting Socket.io Server version 1.7.4 Oct 13 12:36:50 rivo volumio[4053]: info: Plugin fusiondsp is not enabled Oct 13 12:36:50 rivo volumio[4053]: info: Loading i18n strings for locale en Oct 13 12:36:50 rivo volumio[4053]: Updating browse sources language Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::initPlayerControls Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: Express server listening on port 3000 Oct 13 12:36:50 rivo volumio[4053]: [Metrics] WebUI: 12s 641.51ms Oct 13 12:36:50 rivo volumio[4053]: info: CoreStateMachine::resetVolumioState Oct 13 12:36:50 rivo volumio[4053]: info: CoreStateMachine::getcurrentVolume Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 12:36:50 rivo volumio[4053]: info: CoreStateMachine::pushState Oct 13 12:36:50 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:36:50 rivo volumio[4053]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 12:36:50 rivo volumio[4053]: info: Completed loading Core Plugins Oct 13 12:36:50 rivo volumio[4053]: info: Preparing to generate the ALSA configuration file Oct 13 12:36:50 rivo volumio[4053]: info: Volumio Network Manager: Network status updated: 1 Oct 13 12:36:50 rivo volumio[4053]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 12:36:50 rivo volumio[4053]: 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:50 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 12:36:51 rivo volumio[4053]: info: Reloading queue from file Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:51 rivo volumio[4053]: info: Received Get System Version Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:51 rivo volumio[4053]: info: Received Get System Info Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:36:51 rivo volumio[4053]: info: Discovery: Getting this device information Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioGetState Oct 13 12:36:51 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 13 12:36:51 rivo sudo[4204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 13 12:36:51 rivo sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4209]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:51 rivo sudo[4209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4209]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: Error getting the SSID Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo sudo[4204]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo sudo[4215]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:51 rivo sudo[4215]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: Error getting the SSID Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo sudo[4215]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 13 12:36:51 rivo sudo[4224]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:36:51 rivo sudo[4224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioGetState Oct 13 12:36:51 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:51 rivo sudo[4228]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: Error getting the SSID Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:36:51 rivo sudo[4224]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:36:51 rivo volumio[4053]: info: CoreStateMachine::getQueue Oct 13 12:36:51 rivo volumio[4053]: info: CorePlayQueue::getQueue Oct 13 12:36:51 rivo sudo[4228]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4228]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo sudo[4232]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:51 rivo sudo[4232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio[4053]: info: CoreStateMachine::setRepeat null single undefined Oct 13 12:36:51 rivo volumio[4053]: info: CoreStateMachine::pushState Oct 13 12:36:51 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:51 rivo volumio[4053]: info: CoreStateMachine::setRandom null Oct 13 12:36:51 rivo volumio[4053]: info: CoreStateMachine::pushState Oct 13 12:36:51 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioPushState Oct 13 12:36:51 rivo sudo[4232]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: Asound.conf file unchanged, so no further update is needed Oct 13 12:36:51 rivo volumio[4053]: info: Output device has changed, restarting MPD Oct 13 12:36:51 rivo volumio[4053]: info: ___________ START PLUGINS ___________ Oct 13 12:36:51 rivo sudo[4240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:36:51 rivo sudo[4240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4240]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: ControllerMpd::onStart: Initializing MPD Oct 13 12:36:51 rivo sudo[4242]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:36:51 rivo sudo[4242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio[4053]: info: Creating MPD Configuration file Oct 13 12:36:51 rivo sudo[4245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 13 12:36:51 rivo sudo[4245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio[4053]: info: Adding METAVOLUMIO REST API Endpoints Oct 13 12:36:51 rivo volumio[4053]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:51 rivo volumio[4053]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:51 rivo volumio[4053]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:51 rivo volumio[4053]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:36:51 rivo volumio[4053]: info: Nova UI: unpacking bundled version 0.260915.4 Oct 13 12:36:51 rivo sudo[4248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:36:51 rivo sudo[4248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:51 rivo volumio[4053]: info: [1791884211366] CoreMusicLibrary::Adding element Last_100 Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:51 rivo sudo[4248]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: Streaming services startup Oct 13 12:36:51 rivo volumio[4053]: info: Starting Streaming Daemon Oct 13 12:36:51 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 12:36:51 rivo sudo[4251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:36:51 rivo sudo[4251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:51 rivo sudo[4245]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:51 rivo volumio[4053]: info: [1791884211440] CoreMusicLibrary::Adding element Webradio Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:51 rivo sudo[4256]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 12:36:51 rivo sudo[4256]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo volumio5-onboarding[4259]: time=2026-10-13T12:36:51.454+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:51 rivo volumio[4053]: info: Initializing BBC Radios Oct 13 12:36:51 rivo sudo[4256]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:36:51 rivo volumio[4053]: error: Hi Res Audio Failed Login: Missing Login Data Oct 13 12:36:51 rivo volumio[4053]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 13 12:36:51 rivo volumio[4053]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 13 12:36:51 rivo volumio[4053]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 13 12:36:51 rivo volumio[4053]: info: Touch Event Listener Process Starting Oct 13 12:36:51 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Oct 13 12:36:51 rivo volumio[4053]: info: Adding TIDAL to Browse Sources Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:36:51 rivo volumio[4053]: info: [1791884211563] CoreMusicLibrary::Adding element TIDAL Oct 13 12:36:51 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:51 rivo volumio[4053]: Cannot find translation for source TIDAL Oct 13 12:36:51 rivo volumio[4053]: info: Refreshing TIDAL token Oct 13 12:36:51 rivo sudo[4289]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 13 12:36:51 rivo sudo[4289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4289]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo sudo[4292]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 13 12:36:51 rivo sudo[4292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4292]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo sudo[4295]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 13 12:36:51 rivo sudo[4295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4295]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 13 12:36:51 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 12:36:51 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 13 12:36:51 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 12:36:51 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 12:36:51 rivo sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 13 12:36:51 rivo sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 12:36:51 rivo sudo[4298]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 12:36:51 rivo sudo[4303]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 13 12:36:51 rivo sudo[4303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4303]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: Apply VIM3L Onboard LEDs Settings Oct 13 12:36:51 rivo volumio[4053]: info: Turning On Onboard LEDs Oct 13 12:36:51 rivo volumio[4053]: info: Starting CEC Event listener Oct 13 12:36:51 rivo volumio[4053]: info: Volumio Calling Home Oct 13 12:36:51 rivo sudo[4307]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 13 12:36:51 rivo sudo[4307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4307]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo sudo[4314]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 13 12:36:51 rivo sudo[4314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:51 rivo sudo[4314]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:51 rivo volumio[4053]: info: Stopping AccessToken refresher cron for QOBUZ Oct 13 12:36:51 rivo volumio[4053]: info: AccessToken refresher cron started for QOBUZ Oct 13 12:36:51 rivo volumio[4053]: info: Adding QOBUZ REST API Endpoints Oct 13 12:36:52 rivo sudo[4301]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 12:36:52 rivo sudo[4301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 12:36:52 rivo sudo[4301]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:52 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:52 rivo volumio[4053]: info: Serial port opened successfully Oct 13 12:36:52 rivo volumio[4053]: info: Sending serial start messages Oct 13 12:36:52 rivo sudo[4323]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:52 rivo sudo[4323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:52 rivo sudo[4325]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:52 rivo sudo[4325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:52 rivo sudo[4323]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:52 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:36:52 rivo volumio[4053]: info: Reporting MCU Network Status: 1 Oct 13 12:36:52 rivo sudo[4325]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:52 rivo volumio[4053]: info: MP1 GPIO: Signalled system ready via GPIO Oct 13 12:36:52 rivo volumio[4053]: info: Discovery: adding d8c619e6-8b2e-4df9-a70c-e9093abc295c Oct 13 12:36:52 rivo volumio[4053]: info: Discovery: Found device Rivo Oct 13 12:36:52 rivo volumio[4053]: info: CoreCommandRouter::volumioGetState Oct 13 12:36:52 rivo volumio[4053]: info: CorePlayQueue::getTrack 0 Oct 13 12:36:52 rivo volumio[4053]: Searching all installed plugins Oct 13 12:36:52 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:36:52 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: , search Oct 13 12:36:52 rivo volumio[4053]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 12:36:52 rivo volumio[4053]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:52 rivo volumio[4053]: TypeError: Cannot read properties of undefined (reading 'then') Oct 13 12:36:52 rivo volumio[4053]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1596:17) Oct 13 12:36:52 rivo volumio[4053]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 13 12:36:52 rivo volumio[4053]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 13 12:36:52 rivo volumio[4053]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 13 12:36:52 rivo volumio[4053]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Oct 13 12:36:52 rivo volumio[4053]: at Socket.emit (node:events:514:28) Oct 13 12:36:52 rivo volumio[4053]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 12:36:52 rivo volumio[4053]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 13 12:36:52 rivo volumio[4053]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:36:53 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:36:53 rivo sudo[4339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 12:35' Oct 13 12:36:53 rivo sudo[4339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:53 rivo sudo[4339]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:53 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:53] [connect] Successful connection Oct 13 12:36:54 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:54] [error] handle_read_http_response error: asio.system:104 (Connection reset by peer) Oct 13 12:36:54 rivo volumio-remote-updater[2950]: [2026-10-13 12:36:54] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Oct 13 12:36:54 rivo volumio5-onboarding[4259]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:42924->127.0.0.1:3000: read: connection reset by peer Oct 13 12:36:54 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:54 rivo sudo[4242]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:54 rivo sudo[4251]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:54 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Oct 13 12:36:54 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:54 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:54 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:54 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:54 rivo volumio5-onboarding[4347]: time=2026-10-13T12:36:54.292+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:54 rivo volumio5-onboarding[4347]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:54 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 2. Oct 13 12:36:54 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:36:54 rivo systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 13 12:36:54 rivo systemd[1]: Started volumio.service - Volumio Backend Module. Oct 13 12:36:54 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:54 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:54 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:36:54 rivo volumio5-onboarding[4366]: time=2026-10-13T12:36:54.639+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:54 rivo volumio5-onboarding[4366]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:54 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 3. Oct 13 12:36:55 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo volumio5-onboarding[4384]: time=2026-10-13T12:36:55.082+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:55 rivo volumio5-onboarding[4384]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Oct 13 12:36:55 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo volumio5-onboarding[4392]: time=2026-10-13T12:36:55.330+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:36:55 rivo volumio5-onboarding[4392]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: dial tcp 127.0.0.1:3000: connect: connection refused Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Oct 13 12:36:55 rivo systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Start request repeated too quickly. Oct 13 12:36:55 rivo systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Oct 13 12:36:55 rivo systemd[1]: Failed to start volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:36:55 rivo mpd[4321]: 2026-10-13T12:36:55 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 13 12:36:55 rivo systemd[1]: Started mpd.service - Music Player Daemon. Oct 13 12:36:56 rivo volumio[4364]: info: ------------------------------------------- Oct 13 12:36:56 rivo volumio[4364]: info: ----- Volumio3 ---- Oct 13 12:36:56 rivo volumio[4364]: info: ------------------------------------------- Oct 13 12:36:56 rivo volumio[4364]: info: ----- System startup ---- Oct 13 12:36:56 rivo volumio[4364]: info: ------------------------------------------- Oct 13 12:36:57 rivo volumio[4364]: info: MYVOLUMIO Environment detected Oct 13 12:36:57 rivo volumio[4364]: info: Plugin folders cleanup Oct 13 12:36:57 rivo volumio[4364]: info: Scanning into folder /volumio/app/plugins/ Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category audio_interface Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category miscellanea Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category music_service Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category plugins.json Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category system_controller Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category user_interface Oct 13 12:36:57 rivo volumio[4364]: info: Scanning into folder /data/plugins/ Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category audio_interface Oct 13 12:36:57 rivo volumio[4364]: info: Scanning category music_service Oct 13 12:36:57 rivo volumio[4364]: info: Plugin folders cleanup completed Oct 13 12:36:57 rivo volumio[4364]: info: ------------------------------------------- Oct 13 12:36:57 rivo volumio[4364]: info: ----- Core plugins startup ---- Oct 13 12:36:57 rivo volumio[4364]: info: ------------------------------------------- Oct 13 12:36:57 rivo volumio[4364]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin upnp to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin raat to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 12:36:57 rivo volumio[4364]: info: Loading plugins from folder /data/plugins/ Oct 13 12:36:57 rivo volumio[4364]: info: Loading plugin "system"... Oct 13 12:36:57 rivo volumio[4364]: info: Loading plugin "appearance"... Oct 13 12:36:58 rivo volumio[4364]: info: Loading plugin "network"... Oct 13 12:36:58 rivo volumio[4364]: info: Refreshing Cached IP Addresses Oct 13 12:36:58 rivo sudo[4416]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:36:58 rivo sudo[4416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:58 rivo volumio[4364]: info: Loading plugin "services"... Oct 13 12:36:58 rivo volumio[4364]: info: Loading plugin "volumio5onboarding"... Oct 13 12:36:58 rivo sudo[4418]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:36:58 rivo sudo[4418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:58 rivo sudo[4416]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:58 rivo volumio[4364]: info: Loading plugin "alsa_controller"... Oct 13 12:36:58 rivo sudo[4418]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:59 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "wizard"... Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "networkfs"... Oct 13 12:36:59 rivo volumio[4364]: info: Starting Udev Watcher for removable devices Oct 13 12:36:59 rivo volumio[4364]: info: Mounting Device EFI Oct 13 12:36:59 rivo sudo[4447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:59 rivo sudo[4447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:59 rivo sudo[4447]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:59 rivo volumio[4364]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:59 rivo volumio[4364]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:59 rivo volumio[4364]: error: Failed to mount EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:36:59 rivo volumio[4364]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:36:59 rivo volumio[4364]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:59 rivo volumio[4364]: info: Mounting Device CORSAIR2 Oct 13 12:36:59 rivo sudo[4453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/CORSAIR2 -o noatime Oct 13 12:36:59 rivo sudo[4453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:36:59 rivo sudo[4453]: pam_unix(sudo:session): session closed for user root Oct 13 12:36:59 rivo volumio[4364]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:59 rivo volumio[4364]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:59 rivo volumio[4364]: error: Failed to mount CORSAIR2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/CORSAIR2" -o noatime Oct 13 12:36:59 rivo volumio[4364]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:36:59 rivo volumio[4364]: dmesg(1) may have more information after failed mount system call. Oct 13 12:36:59 rivo volumio[4364]: info: Ignoring mount for partition: BOOT Oct 13 12:36:59 rivo volumio[4364]: info: Ignoring mount for partition: volumio Oct 13 12:36:59 rivo volumio[4364]: info: Ignoring mount for partition: volumio_data Oct 13 12:36:59 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "volumio_command_line_client"... Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "upnp"... Oct 13 12:36:59 rivo volumio[4364]: info: [1791884219341] Starting Upmpd Daemon Oct 13 12:36:59 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "my_music"... Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "mpd"... Oct 13 12:36:59 rivo volumio[4364]: info: Plugin upnp_browser is not enabled Oct 13 12:36:59 rivo volumio[4364]: info: Plugin bluetooth is not enabled Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "alarm-clock"... Oct 13 12:36:59 rivo volumio[4364]: info: Loading plugin "manifestui"... Oct 13 12:37:00 rivo volumio[4364]: info: Loading plugin "metavolumio"... Oct 13 12:37:01 rivo volumio[4364]: info: Loading plugin "novaui"... Oct 13 12:37:01 rivo volumio[4364]: info: Applying required configuration parameters for plugin novaui Oct 13 12:37:01 rivo volumio[4364]: info: Plugin airplay_emulation is not enabled Oct 13 12:37:01 rivo volumio[4364]: info: Plugin cd_controller is not enabled Oct 13 12:37:01 rivo volumio[4364]: info: Loading plugin "last_100"... Oct 13 12:37:01 rivo volumio[4364]: info: Plugin qobuzconnect is not enabled Oct 13 12:37:01 rivo volumio[4364]: info: Plugin raat is not enabled Oct 13 12:37:01 rivo volumio[4364]: info: Loading plugin "streaming_services"... Oct 13 12:37:02 rivo volumio[4364]: info: Plugin tidalconnect is not enabled Oct 13 12:37:02 rivo volumio[4364]: info: Loading plugin "webradio"... Oct 13 12:37:02 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:37:03 rivo volumio[4364]: info: Loading plugin "i2s_dacs"... Oct 13 12:37:03 rivo volumio[4364]: info: I2S DAC not set, start Auto-detection Oct 13 12:37:03 rivo volumio[4364]: info: Loading plugin "volumiodiscovery"... Oct 13 12:37:03 rivo volumio[4364]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:37:03 rivo volumio[4364]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:03 rivo volumio[4364]: *** WARNING *** For more information see Oct 13 12:37:03 rivo volumio[4364]: *** 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:37:03 rivo node[4364]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:37:03 rivo volumio[4364]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:03 rivo volumio[4364]: *** WARNING *** For more information see Oct 13 12:37:03 rivo node[4364]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:03 rivo node[4364]: *** WARNING *** For more information see Oct 13 12:37:03 rivo node[4364]: *** 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:37:03 rivo node[4364]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:03 rivo volumio[4364]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 12:37:03 rivo node[4364]: *** WARNING *** For more information see Oct 13 12:37:03 rivo volumio[4364]: info: Discovery: Started advertising with name: Rivo Oct 13 12:37:03 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:37:03 rivo volumio[4364]: info: Plugin rp2 is not enabled Oct 13 12:37:03 rivo volumio[4364]: info: Plugin multiroom is not enabled Oct 13 12:37:03 rivo volumio[4364]: info: Loading plugin "outputs"... Oct 13 12:37:03 rivo volumio[4364]: info: Loading plugin "albumart"... Oct 13 12:37:03 rivo volumio[4364]: info: Plugin example_plugin is not enabled Oct 13 12:37:03 rivo volumio[4364]: info: Loading plugin "hi_res_audio"... Oct 13 12:37:04 rivo volumio-remote-updater[2950]: [2026-10-13 12:37:04] [connect] Successful connection Oct 13 12:37:04 rivo volumio[4460]: Forking 3 albumart workers Oct 13 12:37:05 rivo volumio[4364]: info: Applying required configuration parameters for plugin hi_res_audio Oct 13 12:37:05 rivo volumio[4364]: info: Loading plugin "inputs"... Oct 13 12:37:05 rivo volumio[4467]: Starting albumart workers Oct 13 12:37:05 rivo volumio[4468]: Starting albumart workers Oct 13 12:37:05 rivo volumio[4469]: Starting albumart workers Oct 13 12:37:05 rivo volumio[4364]: info: Loading plugin "qobuz"... Oct 13 12:37:07 rivo volumio[4364]: info: Plugin smart_inputs is not enabled Oct 13 12:37:07 rivo volumio[4364]: info: Loading plugin "tidal"... Oct 13 12:37:08 rivo volumio[4364]: info: Loading plugin "rivocontrol"... Oct 13 12:37:08 rivo volumio[4364]: info: Adding this device properties Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 13 12:37:08 rivo volumio[4364]: info: Setting Additional Device Volumio Properties: [object Object] Oct 13 12:37:08 rivo volumio[4364]: info: Loading plugin "updater_comm"... Oct 13 12:37:08 rivo volumio[4364]: info: Loading plugin "cec_controller"... Oct 13 12:37:08 rivo volumio[4364]: info: Applying required configuration parameters for plugin cec_controller Oct 13 12:37:08 rivo volumio[4364]: info: Plugin mpdemulation is not enabled Oct 13 12:37:08 rivo volumio[4364]: info: Loading plugin "rest_api"... Oct 13 12:37:08 rivo volumio[4364]: info: Loading plugin "websocket"... Oct 13 12:37:08 rivo volumio[4364]: info: Starting Socket.io Server version 1.7.4 Oct 13 12:37:08 rivo volumio[4364]: info: Plugin fusiondsp is not enabled Oct 13 12:37:08 rivo volumio[4364]: info: Loading i18n strings for locale en Oct 13 12:37:08 rivo volumio[4364]: Updating browse sources language Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::initPlayerControls Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: Express server listening on port 3000 Oct 13 12:37:08 rivo volumio[4364]: [Metrics] WebUI: 12s 719.19ms Oct 13 12:37:08 rivo volumio[4364]: info: CoreStateMachine::resetVolumioState Oct 13 12:37:08 rivo volumio[4364]: info: CoreStateMachine::getcurrentVolume Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 12:37:08 rivo volumio[4364]: info: CoreStateMachine::pushState Oct 13 12:37:08 rivo volumio[4364]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:08 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:37:08 rivo volumio[4364]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 12:37:08 rivo volumio[4364]: info: Completed loading Core Plugins Oct 13 12:37:08 rivo volumio[4364]: info: Preparing to generate the ALSA configuration file Oct 13 12:37:09 rivo volumio[4364]: info: Volumio Network Manager: Network status updated: 1 Oct 13 12:37:09 rivo volumio[4364]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 12:37:09 rivo volumio[4364]: 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:37:09 rivo volumio[4364]: info: Reloading queue from file Oct 13 12:37:09 rivo volumio[4364]: info: CoreStateMachine::setRepeat null single undefined Oct 13 12:37:09 rivo volumio[4364]: info: CoreStateMachine::pushState Oct 13 12:37:09 rivo volumio[4364]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:09 rivo volumio[4364]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:09 rivo volumio[4364]: info: CoreStateMachine::setRandom null Oct 13 12:37:09 rivo volumio[4364]: info: CoreStateMachine::pushState Oct 13 12:37:09 rivo volumio[4364]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:09 rivo volumio[4364]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:09 rivo volumio[4364]: Searching plugin music_service/webradio Oct 13 12:37:09 rivo volumio[4364]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 13 12:37:09 rivo volumio[4364]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:37:09 rivo volumio[4364]: TypeError: Cannot read properties of undefined (reading 'search') Oct 13 12:37:09 rivo volumio[4364]: at ControllerWebradio.searchWithTuneIn (/volumio/app/plugins/music_service/webradio/index.js:1009:34) Oct 13 12:37:09 rivo volumio[4364]: at ControllerWebradio.search (/volumio/app/plugins/music_service/webradio/index.js:866:26) Oct 13 12:37:09 rivo volumio[4364]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 13 12:37:09 rivo volumio[4364]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:477:37) Oct 13 12:37:09 rivo volumio[4364]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Oct 13 12:37:09 rivo volumio[4364]: at Socket.emit (node:events:514:28) Oct 13 12:37:09 rivo volumio[4364]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 12:37:09 rivo volumio[4364]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 13 12:37:09 rivo volumio[4364]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:37:10 rivo sudo[4548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 12:36' Oct 13 12:37:10 rivo sudo[4548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:10 rivo sudo[4548]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:10 rivo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 13 12:37:10 rivo systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 13 12:37:10 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:37:10 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:37:11 rivo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Oct 13 12:37:11 rivo systemd[1]: Started dynamicswap.service - dynamicswap service. Oct 13 12:37:11 rivo systemd[1]: Stopped volumio.service - Volumio Backend Module. Oct 13 12:37:11 rivo systemd[1]: Started volumio.service - Volumio Backend Module. Oct 13 12:37:11 rivo systemd[1]: dynamicswap.service: Deactivated successfully. Oct 13 12:37:12 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:37:13 rivo volumio[4569]: info: ------------------------------------------- Oct 13 12:37:13 rivo volumio[4569]: info: ----- Volumio3 ---- Oct 13 12:37:13 rivo volumio[4569]: info: ------------------------------------------- Oct 13 12:37:13 rivo volumio[4569]: info: ----- System startup ---- Oct 13 12:37:13 rivo volumio[4569]: info: ------------------------------------------- Oct 13 12:37:13 rivo volumio[4569]: info: MYVOLUMIO Environment detected Oct 13 12:37:14 rivo volumio[4569]: info: Plugin folders cleanup Oct 13 12:37:14 rivo volumio[4569]: info: Scanning into folder /volumio/app/plugins/ Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category audio_interface Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category miscellanea Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category music_service Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category plugins.json Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category system_controller Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category user_interface Oct 13 12:37:14 rivo volumio[4569]: info: Scanning into folder /data/plugins/ Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category audio_interface Oct 13 12:37:14 rivo volumio[4569]: info: Scanning category music_service Oct 13 12:37:14 rivo volumio[4569]: info: Plugin folders cleanup completed Oct 13 12:37:14 rivo volumio[4569]: info: ------------------------------------------- Oct 13 12:37:14 rivo volumio[4569]: info: ----- Core plugins startup ---- Oct 13 12:37:14 rivo volumio[4569]: info: ------------------------------------------- Oct 13 12:37:14 rivo volumio[4569]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin bluetooth to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin multiroom to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin upnp to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin metavolumio to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin cd_controller to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin qobuzconnect to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin raat to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 12:37:14 rivo volumio[4569]: info: Loading plugins from folder /data/plugins/ Oct 13 12:37:14 rivo volumio[4569]: info: Loading plugin "system"... Oct 13 12:37:14 rivo volumio[4569]: info: Loading plugin "appearance"... Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "network"... Oct 13 12:37:15 rivo volumio[4569]: info: Refreshing Cached IP Addresses Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "services"... Oct 13 12:37:15 rivo sudo[4595]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:37:15 rivo sudo[4595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "volumio5onboarding"... Oct 13 12:37:15 rivo sudo[4595]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "alsa_controller"... Oct 13 12:37:15 rivo sudo[4597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:37:15 rivo sudo[4597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:15 rivo sudo[4597]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:15 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "wizard"... Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "networkfs"... Oct 13 12:37:15 rivo volumio[4569]: info: Starting Udev Watcher for removable devices Oct 13 12:37:15 rivo volumio[4569]: info: Mounting Device EFI Oct 13 12:37:15 rivo sudo[4626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:37:15 rivo sudo[4626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:15 rivo sudo[4626]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:15 rivo volumio[4569]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:37:15 rivo volumio[4569]: dmesg(1) may have more information after failed mount system call. Oct 13 12:37:15 rivo volumio[4569]: error: Failed to mount EFI: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/EFI" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 13 12:37:15 rivo volumio[4569]: mount: /media/EFI: /dev/sda1 already mounted on /media/EFI. Oct 13 12:37:15 rivo volumio[4569]: dmesg(1) may have more information after failed mount system call. Oct 13 12:37:15 rivo volumio[4569]: info: Mounting Device CORSAIR2 Oct 13 12:37:15 rivo sudo[4632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/CORSAIR2 -o noatime Oct 13 12:37:15 rivo sudo[4632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:15 rivo sudo[4632]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:15 rivo volumio[4569]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:37:15 rivo volumio[4569]: dmesg(1) may have more information after failed mount system call. Oct 13 12:37:15 rivo volumio[4569]: error: Failed to mount CORSAIR2: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda2" "/mnt/USB/CORSAIR2" -o noatime Oct 13 12:37:15 rivo volumio[4569]: mount: /media/CORSAIR2: /dev/sda2 already mounted on /media/CORSAIR2. Oct 13 12:37:15 rivo volumio[4569]: dmesg(1) may have more information after failed mount system call. Oct 13 12:37:15 rivo volumio[4569]: info: Ignoring mount for partition: BOOT Oct 13 12:37:15 rivo volumio[4569]: info: Ignoring mount for partition: volumio Oct 13 12:37:15 rivo volumio[4569]: info: Ignoring mount for partition: volumio_data Oct 13 12:37:15 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "volumio_command_line_client"... Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "upnp"... Oct 13 12:37:15 rivo volumio[4569]: info: [1791884235769] Starting Upmpd Daemon Oct 13 12:37:15 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "my_music"... Oct 13 12:37:15 rivo volumio[4569]: info: Loading plugin "mpd"... Oct 13 12:37:16 rivo volumio[4569]: info: Plugin upnp_browser is not enabled Oct 13 12:37:16 rivo volumio[4569]: info: Plugin bluetooth is not enabled Oct 13 12:37:16 rivo volumio[4569]: info: Loading plugin "alarm-clock"... Oct 13 12:37:16 rivo volumio[4569]: info: Loading plugin "manifestui"... Oct 13 12:37:16 rivo volumio[4569]: info: Loading plugin "metavolumio"... Oct 13 12:37:17 rivo volumio[4569]: info: Loading plugin "novaui"... Oct 13 12:37:17 rivo volumio[4569]: info: Applying required configuration parameters for plugin novaui Oct 13 12:37:17 rivo volumio[4569]: info: Plugin airplay_emulation is not enabled Oct 13 12:37:17 rivo volumio[4569]: info: Plugin cd_controller is not enabled Oct 13 12:37:17 rivo volumio[4569]: info: Loading plugin "last_100"... Oct 13 12:37:17 rivo volumio[4569]: info: Plugin qobuzconnect is not enabled Oct 13 12:37:17 rivo volumio[4569]: info: Plugin raat is not enabled Oct 13 12:37:17 rivo volumio[4569]: info: Loading plugin "streaming_services"... Oct 13 12:37:18 rivo volumio[4569]: info: Plugin tidalconnect is not enabled Oct 13 12:37:18 rivo volumio[4569]: info: Loading plugin "webradio"... Oct 13 12:37:19 rivo volumio-remote-updater[2950]: [2026-10-13 12:37:19] [connect] Successful connection Oct 13 12:37:19 rivo volumio[4569]: info: Loading plugin "i2s_dacs"... Oct 13 12:37:19 rivo volumio[4569]: info: I2S DAC not set, start Auto-detection Oct 13 12:37:19 rivo volumio[4569]: info: Loading plugin "volumiodiscovery"... Oct 13 12:37:20 rivo volumio[4569]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:37:20 rivo volumio[4569]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:20 rivo volumio[4569]: *** WARNING *** For more information see Oct 13 12:37:20 rivo volumio[4569]: *** 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:37:20 rivo volumio[4569]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:20 rivo node[4569]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 12:37:20 rivo volumio[4569]: *** WARNING *** For more information see Oct 13 12:37:20 rivo node[4569]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:20 rivo node[4569]: *** WARNING *** For more information see Oct 13 12:37:20 rivo node[4569]: *** 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:37:20 rivo node[4569]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 12:37:20 rivo node[4569]: *** WARNING *** For more information see Oct 13 12:37:20 rivo volumio[4569]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 12:37:20 rivo volumio[4569]: info: Discovery: Started advertising with name: Rivo Oct 13 12:37:20 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 12:37:20 rivo volumio[4569]: info: Plugin rp2 is not enabled Oct 13 12:37:20 rivo volumio[4569]: info: Plugin multiroom is not enabled Oct 13 12:37:20 rivo volumio[4569]: info: Loading plugin "outputs"... Oct 13 12:37:20 rivo volumio[4569]: info: Loading plugin "albumart"... Oct 13 12:37:20 rivo volumio[4569]: info: Plugin example_plugin is not enabled Oct 13 12:37:20 rivo volumio[4569]: info: Loading plugin "hi_res_audio"... Oct 13 12:37:20 rivo volumio[4639]: Forking 3 albumart workers Oct 13 12:37:21 rivo volumio[4569]: info: Applying required configuration parameters for plugin hi_res_audio Oct 13 12:37:21 rivo volumio[4569]: info: Loading plugin "inputs"... Oct 13 12:37:21 rivo volumio[4647]: Starting albumart workers Oct 13 12:37:22 rivo volumio[4648]: Starting albumart workers Oct 13 12:37:22 rivo volumio[4646]: Starting albumart workers Oct 13 12:37:22 rivo volumio[4569]: info: Loading plugin "qobuz"... Oct 13 12:37:22 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:37:23 rivo volumio[4569]: info: Plugin smart_inputs is not enabled Oct 13 12:37:23 rivo volumio[4569]: info: Loading plugin "tidal"... Oct 13 12:37:24 rivo volumio[4569]: info: Loading plugin "rivocontrol"... Oct 13 12:37:24 rivo volumio[4569]: info: Adding this device properties Oct 13 12:37:24 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Oct 13 12:37:24 rivo volumio[4569]: info: Setting Additional Device Volumio Properties: [object Object] Oct 13 12:37:24 rivo volumio[4569]: info: Loading plugin "updater_comm"... Oct 13 12:37:24 rivo volumio[4569]: info: Loading plugin "cec_controller"... Oct 13 12:37:25 rivo volumio[4569]: info: Applying required configuration parameters for plugin cec_controller Oct 13 12:37:25 rivo volumio[4569]: info: Plugin mpdemulation is not enabled Oct 13 12:37:25 rivo volumio[4569]: info: Loading plugin "rest_api"... Oct 13 12:37:25 rivo volumio[4569]: info: Loading plugin "websocket"... Oct 13 12:37:25 rivo volumio[4569]: info: Starting Socket.io Server version 1.7.4 Oct 13 12:37:25 rivo volumio[4569]: info: Plugin fusiondsp is not enabled Oct 13 12:37:25 rivo volumio[4569]: info: Loading i18n strings for locale en Oct 13 12:37:25 rivo volumio[4569]: Updating browse sources language Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::initPlayerControls Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: Express server listening on port 3000 Oct 13 12:37:25 rivo volumio[4569]: [Metrics] WebUI: 12s 727.79ms Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::resetVolumioState Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::getcurrentVolume Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::pushState Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:37:25 rivo volumio[4569]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 13 12:37:25 rivo volumio[4569]: info: Completed loading Core Plugins Oct 13 12:37:25 rivo volumio[4569]: info: Preparing to generate the ALSA configuration file Oct 13 12:37:25 rivo volumio[4569]: info: Volumio Network Manager: Network status updated: 1 Oct 13 12:37:25 rivo volumio[4569]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 13 12:37:25 rivo volumio[4569]: 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:37:25 rivo volumio[4569]: info: Reloading queue from file Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:25 rivo volumio[4569]: info: Received Get System Version Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:37:25 rivo volumio[4569]: info: Received Get System Info Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 12:37:25 rivo volumio[4569]: info: Discovery: Getting this device information Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioGetState Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 13 12:37:25 rivo sudo[4718]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 13 12:37:25 rivo sudo[4718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4724]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:37:25 rivo sudo[4724]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4729]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:37:25 rivo sudo[4729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4718]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo volumio[4569]: info: CALLMETHOD: miscellanea novaui getTheme [object Object] Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: Error getting the SSID Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: novaui , getTheme Oct 13 12:37:25 rivo sudo[4729]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioGetState Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioGetQueue Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::getQueue Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getQueue Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: Error getting the SSID Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo sudo[4724]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo sudo[4741]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:37:25 rivo sudo[4741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4736]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 13 12:37:25 rivo sudo[4736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: Error getting the SSID Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo kernel: dhd_ioctl_entry: Interface is down Oct 13 12:37:25 rivo sudo[4736]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo sudo[4744]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:37:25 rivo sudo[4744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4741]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo sudo[4744]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::setRepeat null single undefined Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::pushState Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::setRandom null Oct 13 12:37:25 rivo volumio[4569]: info: CoreStateMachine::pushState Oct 13 12:37:25 rivo volumio[4569]: info: CorePlayQueue::getTrack 0 Oct 13 12:37:25 rivo volumio[4569]: info: CoreCommandRouter::volumioPushState Oct 13 12:37:25 rivo sudo[4753]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 12:37:25 rivo sudo[4753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4755]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 12:37:25 rivo sudo[4755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:25 rivo sudo[4753]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:25 rivo sudo[4755]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo volumio[4569]: info: Asound.conf file unchanged, so no further update is needed Oct 13 12:37:26 rivo volumio[4569]: info: Output device has changed, restarting MPD Oct 13 12:37:26 rivo volumio[4569]: info: ___________ START PLUGINS ___________ Oct 13 12:37:26 rivo sudo[4762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:37:26 rivo sudo[4762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4762]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo volumio[4569]: info: ControllerMpd::onStart: Initializing MPD Oct 13 12:37:26 rivo sudo[4764]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:37:26 rivo volumio[4569]: info: Creating MPD Configuration file Oct 13 12:37:26 rivo sudo[4764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Oct 13 12:37:26 rivo sudo[4767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo volumio[4569]: info: Adding METAVOLUMIO REST API Endpoints Oct 13 12:37:26 rivo volumio[4569]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:37:26 rivo volumio[4569]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:37:26 rivo volumio[4569]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:37:26 rivo volumio[4569]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 13 12:37:26 rivo volumio[4569]: info: Nova UI: unpacking bundled version 0.260915.4 Oct 13 12:37:26 rivo sudo[4770]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 13 12:37:26 rivo sudo[4770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:37:26 rivo volumio[4569]: info: [1791884246116] CoreMusicLibrary::Adding element Last_100 Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:26 rivo volumio[4569]: info: Streaming services startup Oct 13 12:37:26 rivo volumio[4569]: info: Starting Streaming Daemon Oct 13 12:37:26 rivo sudo[4773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 13 12:37:26 rivo sudo[4773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4770]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 13 12:37:26 rivo sudo[4767]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:37:26 rivo volumio[4569]: info: [1791884246203] CoreMusicLibrary::Adding element Webradio Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:26 rivo sudo[4781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 13 12:37:26 rivo sudo[4781]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:37:26 rivo volumio[4569]: info: Initializing BBC Radios Oct 13 12:37:26 rivo volumio5-onboarding[4779]: time=2026-10-13T12:37:26.230+03:00 level=INFO msg="running volumio4-device-gateway" version=415db8e7+CHANGES buildDate=2026-09-15T15:08:58Z Oct 13 12:37:26 rivo systemd[1]: mpd.service: Deactivated successfully. Oct 13 12:37:26 rivo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 13 12:37:26 rivo systemd[1]: mpd.socket: Deactivated successfully. Oct 13 12:37:26 rivo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 13 12:37:26 rivo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 13 12:37:26 rivo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 12:37:26 rivo volumio[4569]: error: Hi Res Audio Failed Login: Missing Login Data Oct 13 12:37:26 rivo volumio[4569]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 13 12:37:26 rivo volumio[4569]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 13 12:37:26 rivo volumio[4569]: info: Initializing Serial Communication on port /dev/ttyS3 Oct 13 12:37:26 rivo volumio[4569]: info: Touch Event Listener Process Starting Oct 13 12:37:26 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Oct 13 12:37:26 rivo volumio[4569]: info: Adding TIDAL to Browse Sources Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 12:37:26 rivo volumio[4569]: info: [1791884246319] CoreMusicLibrary::Adding element TIDAL Oct 13 12:37:26 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:26 rivo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 13 12:37:26 rivo volumio[4569]: Cannot find translation for source TIDAL Oct 13 12:37:26 rivo volumio[4569]: info: Refreshing TIDAL token Oct 13 12:37:26 rivo sudo[4781]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4814]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Oct 13 12:37:26 rivo sudo[4814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4814]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4818]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Oct 13 12:37:26 rivo sudo[4818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4818]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4821]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Oct 13 12:37:26 rivo sudo[4821]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4821]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4824]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Oct 13 12:37:26 rivo sudo[4824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4824]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4827]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Oct 13 12:37:26 rivo sudo[4827]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4827]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo volumio[4569]: info: Apply VIM3L Onboard LEDs Settings Oct 13 12:37:26 rivo volumio[4569]: info: Turning On Onboard LEDs Oct 13 12:37:26 rivo volumio[4569]: info: Starting CEC Event listener Oct 13 12:37:26 rivo volumio[4569]: info: Volumio Calling Home Oct 13 12:37:26 rivo sudo[4831]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Oct 13 12:37:26 rivo sudo[4831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo sudo[4831]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo sudo[4838]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Oct 13 12:37:26 rivo sudo[4838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 13 12:37:26 rivo volumio[4569]: info: Stopping AccessToken refresher cron for QOBUZ Oct 13 12:37:26 rivo sudo[4838]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:26 rivo volumio[4569]: info: AccessToken refresher cron started for QOBUZ Oct 13 12:37:26 rivo volumio[4569]: info: Adding QOBUZ REST API Endpoints Oct 13 12:37:26 rivo sudo[4815]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 13 12:37:26 rivo sudo[4815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 13 12:37:26 rivo sudo[4815]: pam_unix(sudo:session): session closed for user root Oct 13 12:37:27 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:37:27 rivo volumio[4569]: info: Serial port opened successfully Oct 13 12:37:27 rivo volumio[4569]: info: Sending serial start messages Oct 13 12:37:27 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 12:37:27 rivo volumio[4569]: info: Reporting MCU Network Status: 1 Oct 13 12:37:27 rivo volumio[4569]: info: MP1 GPIO: Signalled system ready via GPIO Oct 13 12:37:27 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 13 12:37:27 rivo volumio[4569]: Searching all installed plugins Oct 13 12:37:27 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 12:37:27 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: , search Oct 13 12:37:27 rivo volumio[4569]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 13 12:37:27 rivo volumio[4569]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:37:27 rivo volumio[4569]: TypeError: Cannot read properties of undefined (reading 'then') Oct 13 12:37:27 rivo volumio[4569]: at ControllerMpd.search (/volumio/app/plugins/music_service/mpd/index.js:1596:17) Oct 13 12:37:27 rivo volumio[4569]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Oct 13 12:37:27 rivo volumio[4569]: at CoreMusicLibrary.searchOnPlugin (/volumio/app/musiclibrary.js:668:44) Oct 13 12:37:27 rivo volumio[4569]: at CoreMusicLibrary.search (/volumio/app/musiclibrary.js:455:27) Oct 13 12:37:27 rivo volumio[4569]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:485:44) Oct 13 12:37:27 rivo volumio[4569]: at Socket.emit (node:events:514:28) Oct 13 12:37:27 rivo volumio[4569]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 12:37:27 rivo volumio[4569]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 13 12:37:27 rivo volumio[4569]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 12:37:28 rivo kernel: cectx ff80023c.aocec: bad iniator with self:CEC RX len 5: 0f 87 00 e0 91 Oct 13 12:37:28 rivo sudo[4855]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 12:36' Oct 13 12:37:28 rivo sudo[4855]: 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"