-- Logs begin at Sun 2026-03-15 20:20:39 CET, end at Sun 2026-05-31 12:45:46 CEST. -- May 31 12:44:02 rivoplus volumio[3291]: info: Adding plugin tidalconnect to MyMusic Plugins May 31 12:44:02 rivoplus volumio[3291]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 12:44:02 rivoplus volumio[3291]: info: Loading plugins from folder /data/plugins/ May 31 12:44:02 rivoplus volumio-time-update[2833]: volumio-time-update-util: System time updated successfully. May 31 12:44:02 rivoplus systemd-timedated[3339]: Changed local time to Sun May 31 12:44:02 2026 May 31 12:44:02 rivoplus sudo[3336]: pam_unix(sudo:session): session closed for user root May 31 12:44:02 rivoplus volumio[3291]: info: Loading plugin "system"... May 31 12:44:02 rivoplus systemd[1]: Started Volumio Time Update Utility. May 31 12:44:02 rivoplus systemd[1]: Starting Daily apt download activities... May 31 12:44:02 rivoplus systemd[1]: Reached target Multi-User System. May 31 12:44:02 rivoplus systemd[1]: Reached target Graphical Interface. May 31 12:44:02 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... May 31 12:44:02 rivoplus systemd[1]: Starting Daily man-db regeneration... May 31 12:44:02 rivoplus volumio[3291]: info: Loading plugin "appearance"... May 31 12:44:02 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 31 12:44:02 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. May 31 12:44:02 rivoplus systemd[1]: man-db.service: Succeeded. May 31 12:44:02 rivoplus systemd[1]: Started Daily man-db regeneration. May 31 12:44:02 rivoplus ntpd[3280]: Soliciting pool server 141.84.43.73 May 31 12:44:02 rivoplus ntpd[3280]: Soliciting pool server 178.63.67.56 May 31 12:44:02 rivoplus ntpd[3280]: Soliciting pool server 104.167.24.26 May 31 12:44:03 rivoplus systemd[1]: apt-daily.service: Succeeded. May 31 12:44:03 rivoplus systemd[1]: Started Daily apt download activities. May 31 12:44:03 rivoplus systemd[1]: Starting Daily apt upgrade and clean activities... May 31 12:44:03 rivoplus ntpd[3280]: Soliciting pool server 49.12.35.6 May 31 12:44:03 rivoplus ntpd[3280]: Soliciting pool server 85.215.64.237 May 31 12:44:03 rivoplus ntpd[3280]: Soliciting pool server 178.63.52.50 May 31 12:44:03 rivoplus systemd[1]: apt-daily-upgrade.service: Succeeded. May 31 12:44:03 rivoplus systemd[1]: Started Daily apt upgrade and clean activities. May 31 12:44:03 rivoplus systemd[1]: Startup finished in 12.916s (kernel) + 26.646s (userspace) = 39.563s. May 31 12:44:03 rivoplus volumio[3291]: info: Loading plugin "network"... May 31 12:44:03 rivoplus volumio[3291]: info: Refreshing Cached IP Addresses May 31 12:44:03 rivoplus sudo[3448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:44:03 rivoplus sudo[3448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:04 rivoplus sudo[3450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:44:04 rivoplus sudo[3450]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:04 rivoplus sudo[3448]: pam_unix(sudo:session): session closed for user root May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "services"... May 31 12:44:04 rivoplus sudo[3450]: pam_unix(sudo:session): session closed for user root May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "volumio5onboarding"... May 31 12:44:04 rivoplus sudo[3458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 12:44:04 rivoplus sudo[3458]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:04 rivoplus kernel: wl_escan_set_scan: ioctl_ver=2 May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "alsa_controller"... May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "wizard"... May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "networkfs"... May 31 12:44:04 rivoplus volumio[3291]: info: Starting Udev Watcher for removable devices May 31 12:44:04 rivoplus ntpd[3280]: Soliciting pool server 94.130.191.198 May 31 12:44:04 rivoplus ntpd[3280]: Soliciting pool server 2a02:a00:1009:6202::123 May 31 12:44:04 rivoplus ntpd[3280]: Soliciting pool server 46.224.156.215 May 31 12:44:04 rivoplus volumio[3291]: info: Ignoring mount for partition: BOOT May 31 12:44:04 rivoplus volumio[3291]: info: Ignoring mount for partition: volumio May 31 12:44:04 rivoplus volumio[3291]: info: Ignoring mount for partition: volumio_data May 31 12:44:04 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "volumio_command_line_client"... May 31 12:44:04 rivoplus volumio[3291]: info: Plugin upnp is not enabled May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "my_music"... May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "mpd"... May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:04 rivoplus volumio[3291]: info: Loading plugin "upnp_browser"... May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus volumio[3291]: info: Starting UPNP Browser May 31 12:44:05 rivoplus volumio[3291]: info: Plugin bluetooth is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Loading plugin "alarm-clock"... May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:05 rivoplus volumio[3291]: info: Loading plugin "manifestui"... May 31 12:44:05 rivoplus volumio[3291]: info: Plugin metavolumio is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Plugin airplay_emulation is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Plugin cd_controller is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Loading plugin "last_100"... May 31 12:44:05 rivoplus volumio[3291]: info: Plugin qobuzconnect is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Plugin raat is not enabled May 31 12:44:05 rivoplus volumio[3291]: info: Loading plugin "streaming_services"... May 31 12:44:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:06 rivoplus volumio-remote-updater[2826]: [2026-05-31 12:44:06] [connect] Successful connection May 31 12:44:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:07 rivoplus volumio[3291]: info: Starting Streaming Service Transparent Proxy May 31 12:44:07 rivoplus volumio[3291]: info: Loading plugin "tidalconnect"... May 31 12:44:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 8 May 31 12:44:07 rivoplus volumio[3291]: info: Loading plugin "webradio"... May 31 12:44:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03d326000 drv_status 0x0 e_type 69 e_states 0 May 31 12:44:08 rivoplus sudo[3458]: pam_unix(sudo:session): session closed for user root May 31 12:44:08 rivoplus volumio[3291]: info: Loading plugin "i2s_dacs"... May 31 12:44:08 rivoplus volumio[3291]: info: I2S DAC not set, start Auto-detection May 31 12:44:08 rivoplus volumio[3291]: info: Loading plugin "volumiodiscovery"... May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** For more information see May 31 12:44:08 rivoplus node[3291]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:44:08 rivoplus volumio[3291]: *** WARNING *** For more information see May 31 12:44:08 rivoplus node[3291]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:44:08 rivoplus node[3291]: *** WARNING *** For more information see May 31 12:44:08 rivoplus node[3291]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 12:44:08 rivoplus node[3291]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:44:08 rivoplus node[3291]: *** WARNING *** For more information see May 31 12:44:08 rivoplus volumio[3291]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 12:44:08 rivoplus volumio[3291]: info: Discovery: Started advertising with name: Rivoplus May 31 12:44:08 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:44:08 rivoplus volumio[3291]: info: Plugin multiroom is not enabled May 31 12:44:08 rivoplus volumio[3291]: info: Loading plugin "outputs"... May 31 12:44:08 rivoplus volumio[3291]: info: Loading plugin "albumart"... May 31 12:44:08 rivoplus volumio[3291]: info: Plugin example_plugin is not enabled May 31 12:44:08 rivoplus volumio[3291]: info: Loading plugin "hi_res_audio"... May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.573bd6c4 does not match aorg 0000000000.00000000 from server@94.130.191.198 xmt 0xedc68f78.b98d76e4 May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.574c1b1e does not match aorg 0000000000.00000000 from server@185.216.176.59 xmt 0xedc68f78.b9597fb6 May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.5750e170 does not match aorg 0000000000.00000000 from server@202.61.195.221 xmt 0xedc68f78.b8a83d76 May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.57556c15 does not match aorg 0000000000.00000000 from server@185.232.69.65 xmt 0xedc68f78.b9942be4 May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.5746848d does not match aorg 0000000000.00000000 from server@178.63.52.50 xmt 0xedc68f78.b971fa96 May 31 12:44:08 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xedc68f78.5749b129 does not match aorg 0000000000.00000000 from server@78.47.168.188 xmt 0xedc68f78.b9b36c66 May 31 12:44:09 rivoplus volumio[3291]: Forking 3 albumart workers May 31 12:44:09 rivoplus volumio[3291]: STREAMING PROXY: Starting server on port 3245 May 31 12:44:09 rivoplus volumio[3291]: Node JS runtime: 14 May 31 12:44:10 rivoplus volumio[3291]: Starting albumart workers May 31 12:44:10 rivoplus volumio[3291]: Starting albumart workers May 31 12:44:10 rivoplus volumio[3291]: Starting albumart workers May 31 12:44:10 rivoplus volumio[3291]: info: Applying required configuration parameters for plugin hi_res_audio May 31 12:44:10 rivoplus volumio[3291]: info: Loading plugin "inputs"... May 31 12:44:11 rivoplus systemd[1]: systemd-fsckd.service: Succeeded. May 31 12:44:11 rivoplus volumio[3291]: info: Loading plugin "qobuz"... May 31 12:44:13 rivoplus sh[2755]: timed out May 31 12:44:13 rivoplus sh[2755]: dhcpcd exited May 31 12:44:13 rivoplus dhcpcd[2814]: timed out May 31 12:44:13 rivoplus dhcpcd[2814]: dhcpcd exited May 31 12:44:13 rivoplus sh[2755]: ifup: failed to bring up eth0 May 31 12:44:13 rivoplus systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 31 12:44:13 rivoplus systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 31 12:44:13 rivoplus volumio[3291]: info: Loading plugin "smart_inputs"... May 31 12:44:14 rivoplus volumio[3291]: info: Loading plugin "tidal"... May 31 12:44:15 rivoplus volumio[3291]: info: Loading plugin "rivopluscontrol"... May 31 12:44:16 rivoplus volumio[3291]: info: Adding this device properties May 31 12:44:16 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties May 31 12:44:16 rivoplus volumio[3291]: info: Setting Additional Device Volumio Properties: [object Object] May 31 12:44:16 rivoplus volumio[3291]: info: Loading plugin "updater_comm"... May 31 12:44:16 rivoplus volumio[3291]: info: Loading plugin "cec_controller"... May 31 12:44:16 rivoplus volumio[3291]: info: Applying required configuration parameters for plugin cec_controller May 31 12:44:16 rivoplus volumio[3291]: info: Plugin mpdemulation is not enabled May 31 12:44:16 rivoplus volumio[3291]: info: Loading plugin "rest_api"... May 31 12:44:16 rivoplus volumio[3291]: info: Loading plugin "websocket"... May 31 12:44:16 rivoplus volumio[3291]: info: Starting Socket.io Server version 2.3.0 May 31 12:44:16 rivoplus volumio[3291]: info: Loading i18n strings for locale de May 31 12:44:16 rivoplus volumio[3291]: Updating browse sources language May 31 12:44:16 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::initPlayerControls May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: Express server listening on port 3000 May 31 12:44:17 rivoplus volumio[3291]: [Metrics] WebUI: 17s 411.21ms May 31 12:44:17 rivoplus volumio[3291]: info: CoreStateMachine::resetVolumioState May 31 12:44:17 rivoplus volumio[3291]: info: CoreStateMachine::getcurrentVolume May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::volumioRetrievevolume May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 31 12:44:17 rivoplus volumio[3291]: info: Completed loading Core Plugins May 31 12:44:17 rivoplus volumio[3291]: info: Preparing to generate the ALSA configuration file May 31 12:44:17 rivoplus volumio[3291]: info: Volumio Network Manager: Network status updated: 2 May 31 12:44:17 rivoplus volumio[3291]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision May 31 12:44:17 rivoplus volumio[3291]: info: Asound.conf file unchanged, so no further update is needed May 31 12:44:17 rivoplus volumio[3291]: info: Output device has changed, restarting MPD May 31 12:44:17 rivoplus sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 12:44:17 rivoplus sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus volumio[3291]: info: ___________ START PLUGINS ___________ May 31 12:44:17 rivoplus sudo[3575]: pam_unix(sudo:session): session closed for user root May 31 12:44:17 rivoplus sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 12:44:17 rivoplus sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus volumio[3291]: info: ControllerMpd::onStart: Initializing MPD May 31 12:44:17 rivoplus volumio[3291]: info: Creating MPD Configuration file May 31 12:44:17 rivoplus sudo[3581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service May 31 12:44:17 rivoplus sudo[3581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus systemd[1]: Listening on mpd.socket. May 31 12:44:17 rivoplus systemd[1]: Starting Music Player Daemon... May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:44:17 rivoplus sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 12:44:17 rivoplus volumio[3291]: info: [1780224257684] CoreMusicLibrary::Adding element Medienserver May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:17 rivoplus sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus volumio[3291]: info: UPNP Browser: Client initialized successfully May 31 12:44:17 rivoplus sudo[3583]: pam_unix(sudo:session): session closed for user root May 31 12:44:17 rivoplus systemd[1]: Started Volumio5 Onboarding Server. May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:44:17 rivoplus sudo[3581]: pam_unix(sudo:session): session closed for user root May 31 12:44:17 rivoplus volumio[3291]: info: [1780224257731] CoreMusicLibrary::Adding element Last_100 May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:17 rivoplus volumio[3291]: info: Streaming services startup May 31 12:44:17 rivoplus sudo[3589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 12:44:17 rivoplus sudo[3589]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus volumio[3291]: info: Starting Streaming Daemon May 31 12:44:17 rivoplus sudo[3600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 12:44:17 rivoplus sudo[3600]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 31 12:44:17 rivoplus systemd[1]: mpd.service: Succeeded. May 31 12:44:17 rivoplus systemd[1]: Stopped Music Player Daemon. May 31 12:44:17 rivoplus systemd[1]: Starting Music Player Daemon... May 31 12:44:17 rivoplus sudo[3600]: pam_unix(sudo:session): session closed for user root May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:44:17 rivoplus volumio[3291]: info: [1780224257877] CoreMusicLibrary::Adding element Webradio May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:17 rivoplus volumio[3291]: info: Initializing BBC Radios May 31 12:44:17 rivoplus sudo[3609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 12:44:17 rivoplus sudo[3609]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:17 rivoplus sudo[3609]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:18 rivoplus sudo[3606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 12:44:18 rivoplus sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3606]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 31 12:44:18 rivoplus sudo[3606]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus volumio[3291]: info: Adding HIGHRESAUDIO REST API Endpoints May 31 12:44:18 rivoplus volumio[3291]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 31 12:44:18 rivoplus volumio[3291]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 31 12:44:18 rivoplus volumio[3291]: info: Initializing Serial Communication on port /dev/ttyS3 May 31 12:44:18 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 May 31 12:44:18 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 May 31 12:44:18 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 May 31 12:44:18 rivoplus volumio[3291]: info: Touch Event Listener Process Starting May 31 12:44:18 rivoplus volumio[3291]: info: Adding inputs REST Endpoints May 31 12:44:18 rivoplus volumio[3291]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 31 12:44:18 rivoplus volumio[3291]: info: Scanning Audio Inputs May 31 12:44:18 rivoplus sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root May 31 12:44:18 rivoplus sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3630]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus volumio[3291]: info: Checking against Known Cards name May 31 12:44:18 rivoplus volumio[3291]: info: Checking against Known Cards name May 31 12:44:18 rivoplus volumio[3291]: info: Checking against Known Cards name May 31 12:44:18 rivoplus volumio[3291]: info: Checking against Known Cards name May 31 12:44:18 rivoplus volumio[3291]: info: Checking against Known Cards name May 31 12:44:18 rivoplus volumio[3291]: info: Adding Server instance for streaming May 31 12:44:18 rivoplus volumio[3291]: info: Refreshing TIDAL token May 31 12:44:18 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:18.219+02:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z May 31 12:44:18 rivoplus sudo[3643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 May 31 12:44:18 rivoplus sudo[3643]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3643]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus sudo[3646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 May 31 12:44:18 rivoplus sudo[3646]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3646]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus sudo[3649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 May 31 12:44:18 rivoplus sudo[3649]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3649]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 May 31 12:44:18 rivoplus sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3652]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus volumio[3291]: info: Apply VIM3L Onboard LEDs Settings May 31 12:44:18 rivoplus volumio[3291]: info: Turning On Onboard LEDs May 31 12:44:18 rivoplus sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger May 31 12:44:18 rivoplus sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus volumio[3291]: info: Starting CEC Event listener May 31 12:44:18 rivoplus volumio[3291]: info: Volumio Calling Home May 31 12:44:18 rivoplus sudo[3656]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger May 31 12:44:18 rivoplus sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:18 rivoplus sudo[3660]: pam_unix(sudo:session): session closed for user root May 31 12:44:18 rivoplus volumio[3291]: info: Stopping AccessToken refresher cron for QOBUZ May 31 12:44:18 rivoplus volumio[3291]: info: AccessToken refresher cron started for QOBUZ May 31 12:44:18 rivoplus volumio[3291]: info: Adding QOBUZ REST API Endpoints May 31 12:44:18 rivoplus volumio[3291]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::setRepeat false single undefined May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:18 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::setRandom false May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:18 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:18 rivoplus volumio[3291]: info: VolumeController:: Volume=100 Mute =false May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:18 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:18 rivoplus volumio[3291]: info: CoreStateMachine::updateTrackBlock May 31 12:44:18 rivoplus volumio[3291]: info: CorePlayQueue::getTrackBlock May 31 12:44:18 rivoplus volumio[3291]: info: CoreCommandRouter::volumioRetrievevolume May 31 12:44:19 rivoplus volumio[3291]: info: Serial port opened successfully May 31 12:44:19 rivoplus volumio[3291]: info: Sending serial start messages May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: Reporting MCU Network Status: 2 May 31 12:44:19 rivoplus volumio[3291]: info: Touch Event Listener Process Closed May 31 12:44:19 rivoplus volumio[3291]: error: Cannot start Volumio Streaming Daemon May 31 12:44:19 rivoplus volumio[3291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 12:44:19 rivoplus volumio[3291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 12:44:19 rivoplus volumio[3291]: info: MPD Permissions set May 31 12:44:19 rivoplus volumio[3291]: info: MPD Permissions set May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride May 31 12:44:19 rivoplus volumio[3291]: info: Setting Device Volume Override May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioUpdateVolumeSettings May 31 12:44:19 rivoplus volumio[3291]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume May 31 12:44:19 rivoplus volumio[3291]: info: Disabling external Volume Control May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:19 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:19 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 31 12:44:19 rivoplus volumio[3291]: info: Volumio called home May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 31 12:44:19 rivoplus volumio[3291]: info: Setting Additional System Software info: Hardware Revision: 1.0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 31 12:44:19 rivoplus volumio[3291]: info: Setting HW Firmware info: undefined May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 31 12:44:19 rivoplus volumio[3291]: info: Setting HW Version info: 1.0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo May 31 12:44:19 rivoplus volumio[3291]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.3.0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion May 31 12:44:19 rivoplus volumio[3291]: info: Setting HW Firmware info: 0.3.0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion May 31 12:44:19 rivoplus volumio[3291]: info: Setting HW Version info: 1.0 May 31 12:44:19 rivoplus volumio[3291]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 31 12:44:19 rivoplus volumio[3291]: info: MCU Signalled Headphone Mode Disabled May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState May 31 12:44:19 rivoplus volumio[3291]: info: MCU Signalled Sleep Mode Disabled May 31 12:44:19 rivoplus volumio[3291]: info: Enabling Advanced system settings configuration May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections May 31 12:44:19 rivoplus volumio[3291]: info: Additional UI Settings Added for plugin music_service/inputs May 31 12:44:19 rivoplus volumio[3291]: info: MCU Signalled Auto Boot Mode On Power Disabled May 31 12:44:19 rivoplus sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on May 31 12:44:19 rivoplus sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:19 rivoplus sudo[3677]: pam_unix(sudo:session): session closed for user root May 31 12:44:19 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 May 31 12:44:19 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:19 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:19 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:19 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:19.450+02:00 level=INFO msg="system info for aae44798b12920cc576da5f38c6cb74b" deviceName=Rivoplus deviceVariant=rivoplus deviceModel="Volumio Rivo+" softwareVersion=3.912 May 31 12:44:19 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:19 rivoplus volumio[3291]: error: Serial API: Failed to decode command: MAXVOL, message: 100 May 31 12:44:19 rivoplus volumio[3291]: info: Discovery: adding 23c60c43-066c-4e24-b83b-c6f30aa92835 May 31 12:44:19 rivoplus volumio[3291]: info: Discovery: Found device Rivoplus May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:19 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:19 rivoplus volumio[3291]: error: Hi Res Audio Failed Login: [object Object] May 31 12:44:19 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:19 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:19 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:19 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 31 12:44:19 rivoplus volumio[3291]: info: VolumeController:: Volume=100 Mute =false May 31 12:44:19 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:19 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:19 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:19 rivoplus sudo[3693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 May 31 12:44:19 rivoplus sudo[3693]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 12:44:20 rivoplus sudo[3693]: pam_unix(sudo:session): session closed for user root May 31 12:44:20 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:20.015+02:00 level=INFO msg="enabling local network discovery" May 31 12:44:20 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:20.019+02:00 level=INFO msg="enabling BLE discovery" May 31 12:44:20 rivoplus bluetoothd[3202]: src/adapter.c:store_adapter_info() Unable set contents for /usr/var/lib/bluetooth/54:78:C9:90:02:2D/settings: (Failed to create file “/usr/var/lib/bluetooth/54:78:C9:90:02:2D/settings.KUT7P3”: No such file or directory) May 31 12:44:20 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:20.085+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 31 12:44:20 rivoplus volumio[3291]: info: VolumeController:: Volume=100 Mute =false May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:20 rivoplus volumio[3291]: info: MP1 GPIO: Signalled system ready via GPIO May 31 12:44:20 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:20 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:20 rivoplus volumio[3291]: info: Adding Inputs via Serial API May 31 12:44:20 rivoplus volumio[3291]: info: Adding Advanced Audio Settings via Serial API May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections May 31 12:44:20 rivoplus volumio[3291]: info: Additional UI Settings Added for plugin music_service/inputs May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: Access Token successfully retrieved May 31 12:44:20 rivoplus volumio[3291]: info: Successfully retrieved User Session From TIDAL May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":false,"channels":2} May 31 12:44:20 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:20 rivoplus volumio[3291]: info: No code May 31 12:44:20 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:20 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumiosetSourceActiveno-source May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:20 rivoplus volumio[3291]: info: Successfully retrieved User Subscription From TIDAL May 31 12:44:20 rivoplus volumio[3291]: info: Adding TIDAL to Browse Sources May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:44:20 rivoplus volumio[3291]: info: [1780224260859] CoreMusicLibrary::Adding element TIDAL May 31 12:44:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:20 rivoplus volumio[3291]: Cannot find translation for source TIDAL May 31 12:44:20 rivoplus volumio[3291]: info: Adding TIDAL REST API Endpoints May 31 12:44:20 rivoplus volumio[3291]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal May 31 12:44:20 rivoplus volumio[3291]: info: TidalConnect service stoped! May 31 12:44:21 rivoplus volumio[3291]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 12:44:21 rivoplus volumio[3291]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 12:44:21 rivoplus sudo[3708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 12:44:21 rivoplus sudo[3708]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:21 rivoplus systemd[1]: Started Volumio Tidal Connect Service. May 31 12:44:21 rivoplus sudo[3708]: pam_unix(sudo:session): session closed for user root May 31 12:44:21 rivoplus volumio[3291]: info: Turning Off Onboard LEDs May 31 12:44:21 rivoplus sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger May 31 12:44:21 rivoplus sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:21 rivoplus sudo[3716]: pam_unix(sudo:session): session closed for user root May 31 12:44:21 rivoplus sudo[3719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger May 31 12:44:21 rivoplus sudo[3719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:21 rivoplus sudo[3719]: pam_unix(sudo:session): session closed for user root May 31 12:44:21 rivoplus volumio[3291]: info: Onboard LEDs initialized May 31 12:44:21 rivoplus mpd[3629]: May 31 12:44 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 12:44:21 rivoplus systemd[1]: Started Music Player Daemon. May 31 12:44:21 rivoplus sudo[3589]: pam_unix(sudo:session): session closed for user root May 31 12:44:21 rivoplus sudo[3577]: pam_unix(sudo:session): session closed for user root May 31 12:44:21 rivoplus volumio[3291]: error: MPD error: The expression evaluated to a falsy value: May 31 12:44:21 rivoplus volumio[3291]: assert.ok(self.idling) May 31 12:44:21 rivoplus volumio[3291]: error: The expression evaluated to a falsy value: May 31 12:44:21 rivoplus volumio[3291]: assert.ok(self.idling) May 31 12:44:21 rivoplus volumio[3291]: error: updateQueue error: null May 31 12:44:21 rivoplus volumio[3291]: info: MPD running with PID3629 May 31 12:44:21 rivoplus volumio[3291]: ,establishing connection May 31 12:44:21 rivoplus volumio[3291]: error: updateQueue error: null May 31 12:44:21 rivoplus volumio-remote-updater[2826]: [2026-05-31 12:44:21] [connect] Successful connection May 31 12:44:21 rivoplus volumio-remote-updater[2826]: [2026-05-31 12:44:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1780224261 101 May 31 12:44:21 rivoplus volumio[3291]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 May 31 12:44:21 rivoplus volumio[3291]: info: Executing endpoint tc_getconfig May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 12:44:21 rivoplus vtcs[3710]: STARTING TidalConnect services, version: 1.6.1 May 31 12:44:21 rivoplus vtcs[3710]: STARTED TidalConnect services. May 31 12:44:21 rivoplus volumio[3291]: info: Executing endpoint tc_connect May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 31 12:44:21 rivoplus volumio[3291]: info: Connecting to TidalConnect May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 12:44:21 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:21 rivoplus volumio[3291]: info: No code May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::servicePushState May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} May 31 12:44:21 rivoplus volumio[3291]: verbose: CURRENT POSITION 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::syncState stateService stop May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::syncState currentStatus stop May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:21 rivoplus volumio[3291]: info: No code May 31 12:44:21 rivoplus volumio[3291]: info: CoreStateMachine::pushState May 31 12:44:21 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:21 rivoplus volumio[3291]: info: CoreCommandRouter::volumioPushState May 31 12:44:24 rivoplus volumio[3291]: info: TidalConnect service started! May 31 12:44:24 rivoplus volumio[3291]: info: Completed starting Core Plugins May 31 12:44:24 rivoplus volumio[3291]: info: ------------------------------------------- May 31 12:44:24 rivoplus volumio[3291]: info: ----- MyVolumio plugins startup ---- May 31 12:44:24 rivoplus volumio[3291]: info: ------------------------------------------- May 31 12:44:24 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Fetching plans data.... May 31 12:44:26 rivoplus volumio[3291]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 31 12:44:27 rivoplus kernel: fb: mem_free_work, free memory: addr:800000 May 31 12:44:28 rivoplus volumio[3291]: info: Checking for updated MCU Firmware May 31 12:44:28 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:28 rivoplus volumio[3291]: info: Firware on device is on latest version, no need to update May 31 12:44:32 rivoplus systemd[1]: systemd-timedated.service: Succeeded. May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 31 12:44:32 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 31 12:44:33 rivoplus volumio[3291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 31 12:44:33 rivoplus volumio[3291]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 31 12:44:33 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:33 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:33 rivoplus volumio[3291]: info: Starting MyVolumio Remote Streaming Endpoints May 31 12:44:33 rivoplus volumio[3291]: info: MyVolumio login type: Token May 31 12:44:34 rivoplus volumio[3291]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 31 12:44:34 rivoplus volumio[3291]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' May 31 12:44:34 rivoplus volumio[3291]: error: MyVolumio Custom Token format not valid, refreshing it May 31 12:44:35 rivoplus volumio[3291]: info: MyVolumio login type: Token May 31 12:44:35 rivoplus volumio[3291]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 31 12:44:36 rivoplus volumio[3291]: info: MyVolumio token set successfully May 31 12:44:36 rivoplus volumio[3291]: info: MYVOLUMIO: Adding device May 31 12:44:36 rivoplus volumio[3291]: info: MYVOLUMIO: Evaluating Server May 31 12:44:36 rivoplus volumio[3291]: info: MyVolumio status changed May 31 12:44:36 rivoplus volumio[3291]: info: Streaming services startup May 31 12:44:36 rivoplus volumio[3291]: info: Starting Streaming Daemon May 31 12:44:36 rivoplus volumio[3291]: info: Removing browser output: myVolumio user plan is not superstar May 31 12:44:36 rivoplus volumio[3291]: info: Removing audio output: May 31 12:44:36 rivoplus volumio[3291]: info: Stoppping Tunnel 1 May 31 12:44:36 rivoplus sudo[3801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 31 12:44:36 rivoplus sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:36 rivoplus sudo[3801]: pam_unix(sudo:session): session closed for user root May 31 12:44:36 rivoplus sudo[3805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 31 12:44:36 rivoplus sudo[3805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:36 rivoplus volumio[3291]: error: Cannot start Volumio Streaming Daemon May 31 12:44:36 rivoplus volumio[3291]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 31 12:44:36 rivoplus volumio[3291]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 31 12:44:36 rivoplus sudo[3805]: pam_unix(sudo:session): session closed for user root May 31 12:44:36 rivoplus volumio[3291]: info: Remote SSH Stopped May 31 12:44:38 rivoplus volumio[3291]: info: Setting Geolocation for MyVolumio to eu11 May 31 12:44:38 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:38 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:39 rivoplus volumio[3291]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 12:44:39 rivoplus volumio[3291]: info: Updating MyVolumio device info May 31 12:44:39 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:40 rivoplus volumio[3291]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 12:44:43 rivoplus volumio[3291]: info: MYVOLUMIO: Adding device May 31 12:44:43 rivoplus volumio[3291]: info: MYVOLUMIO: Evaluating Server May 31 12:44:44 rivoplus volumio[3291]: error: MyVolumio Plugin failed to start in a timely fashion May 31 12:44:44 rivoplus volumio[3291]: [Metrics] CommandRouter: 43s 290.32ms May 31 12:44:44 rivoplus volumio[3291]: info: CoreCommandRouter::volumiosetStartupVolume May 31 12:44:44 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:44 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:44 rivoplus volumio[3291]: info: CoreCommandRouter::Close All Modals sent May 31 12:44:44 rivoplus volumio[3291]: info: CoreCommandRouter::Close All Modals sent May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 31 12:44:45 rivoplus volumio[3291]: info: Setting Geolocation for MyVolumio to eu7 May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:45 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:45 rivoplus volumio[3291]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 31 12:44:46 rivoplus volumio[3291]: info: Updating MyVolumio device info May 31 12:44:46 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:46 rivoplus volumio[3291]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 12:44:48 rivoplus volumio-remote-updater[2826]: No test mode May 31 12:44:48 rivoplus volumio-remote-updater[2826]: No alpha test mode May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 31 12:44:48 rivoplus volumio[3291]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:44:48 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:44:48 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 May 31 12:44:49 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:49 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:49 rivoplus volumio5-onboarding[3596]: time=2026-05-31T12:44:49.987+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" May 31 12:44:49 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:49 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:49 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:44:50 rivoplus volumio5-onboarding[3596]: failed to bootstrap state: failed to get available plugins: could not get available plugins: context deadline exceeded May 31 12:44:50 rivoplus systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE May 31 12:44:50 rivoplus systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. May 31 12:44:50 rivoplus systemd[1]: volumio5-onboarding.service: Service RestartSec=100ms expired, scheduling restart. May 31 12:44:50 rivoplus systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. May 31 12:44:50 rivoplus systemd[1]: Stopped Volumio5 Onboarding Server. May 31 12:44:50 rivoplus systemd[1]: Started Volumio5 Onboarding Server. May 31 12:44:50 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:50.350+02:00 level=INFO msg="running volumio5-device-gateway" version=687ad144+CHANGES buildDate=2026-02-10T16:50:24Z May 31 12:44:50 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 May 31 12:44:50 rivoplus volumio[3291]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 May 31 12:44:50 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:50 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:50 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:50 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:50.394+02:00 level=INFO msg="system info for aae44798b12920cc576da5f38c6cb74b" deviceName=Rivoplus deviceVariant=rivoplus deviceModel="Volumio Rivo+" softwareVersion=3.912 May 31 12:44:50 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:50 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:50 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 12:44:50 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:50.413+02:00 level=INFO msg="enabling local network discovery" May 31 12:44:50 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:50.414+02:00 level=INFO msg="enabling BLE discovery" May 31 12:44:50 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:50.470+02:00 level=INFO msg="bootstrapping state" hasInternet=true May 31 12:44:50 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:50 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:50 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:50 rivoplus volumio-remote-updater[2826]: No test mode May 31 12:44:50 rivoplus volumio-remote-updater[2826]: No alpha test mode May 31 12:44:50 rivoplus volumio[3291]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:50 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:44:51 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:51.149+02:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" May 31 12:44:51 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:51 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:51 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:44:51 rivoplus volumio[3291]: info: BOOT COMPLETED May 31 12:44:52 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:44:52 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:44:53 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:53.384+02:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=isdLOC6XYQVlH87UiuIpUElWl0p1 tokenExpiry=2026-05-31T13:44:53.384+02:00 May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 31 12:44:53 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:53.967+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.21:54018 May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetBrowseSources May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:53 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:53 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:53 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:54 rivoplus volumio[3291]: verbose: New Socket.io Connection to 192.168.178.45:3000 from 192.168.178.21 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.059+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.21:54145 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.648+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.21:54018 @ 0x1d8aab0" latency=661.910331ms platform=PLATFORM_IOS version=5.260413.0 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.649+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.21:54018 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.651+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.21:54018 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.650+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.21:54018 @ 0x1d8aab0" latency=662.86271ms timeout=10s May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.651+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.652+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_DEVICE_CAPABILITIES_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 31 12:44:54 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:54 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.664+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" name=Rivoplus May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.664+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_DEVICE_NAME_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.668+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" language=de May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.668+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_DEVICE_LANGUAGE_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.673+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" timezone=Europe/Berlin May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.673+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_DEVICE_TIMEZONE_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.675+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" available=true connected=false macAddress= ip4Address= ip6Address= May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.676+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_ETHERNET_INFO_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.685+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" available=true connected=true macAddress=54:78:c9:90:02:2c ip4Address=192.168.178.45/24 ip6Address= ssid="FRITZ!Box 7690 DY" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.687+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_WIFI_INFO_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.687+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" setupComplete=true May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.688+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_DEVICE_SETUP_STATUS_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 12:44:54 rivoplus volumio[3291]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" May 31 12:44:54 rivoplus volumio[3291]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' May 31 12:44:54 rivoplus volumio[3291]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" May 31 12:44:54 rivoplus volumio[3291]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.891+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" selectedOutputId=5 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.892+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_AUDIO_OUTPUTS_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:54 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.926+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" currentVersion=3.912 latestVersion=3.912 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.926+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_SOFTWARE_INFO_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.926+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" status=UPDATE_STATUS_NONE progress=0 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.927+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_SOFTWARE_UPDATE_PROGRESS error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.927+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" userId=isdLOC6XYQVlH87UiuIpUElWl0p1 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.927+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_USER_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.927+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" providers=9 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.928+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_MUSIC_PROVIDERS_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.928+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" plugins=26 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.928+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_PLUGINS_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:54 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.937+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" state=STATUS_STOPPED positionMs=0 volume=100 May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.938+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_PLAYER_STATE_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.938+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:54018 @ 0x1d8aab0" id= title= May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.939+02:00 level=ERROR msg="failed to send event" component=server dst=" @ 0x1d8aab0" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="no addresses to write to" May 31 12:44:54 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:54.939+02:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x1d8aab0" id=972780083 status=STATUS_OK error="no addresses to write to" May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.589+02:00 level=INFO msg="service successfully established" component=discovery/localnet May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.755+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.178.21:54145 @ 0x20fdb60" latency=13.011432ms platform=PLATFORM_IOS version=5.260413.0 May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.755+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.21:54145 @ 0x20fdb60" latency=12.573601ms timeout=10s May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.755+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" May 31 12:44:55 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:55 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:55 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.772+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" name=Rivoplus May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.773+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" language=de May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.780+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" timezone=Europe/Berlin May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.781+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" available=true connected=false macAddress= ip4Address= ip6Address= May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.785+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" available=true connected=true macAddress=54:78:c9:90:02:2c ip4Address=192.168.178.45/24 ip6Address= ssid="FRITZ!Box 7690 DY" May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.786+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" setupComplete=true May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:44:55 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 31 12:44:55 rivoplus volumio[3291]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" May 31 12:44:55 rivoplus volumio[3291]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' May 31 12:44:55 rivoplus volumio[3291]: amixer -c 5 info | grep "Comtrue-inc UAC2 Device" May 31 12:44:55 rivoplus volumio[3291]: Card hw:5 'Device'/'Comtrue-inc Comtrue-inc UAC2 Device at usb-xhci-hcd.0.auto-1.4.3, high speed' May 31 12:44:55 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:55.982+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" selectedOutputId=5 May 31 12:44:56 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:56 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:56 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:56 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:56 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:56 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:56 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.017+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" currentVersion=3.912 latestVersion=3.912 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.018+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" status=UPDATE_STATUS_NONE progress=0 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.018+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" userId=isdLOC6XYQVlH87UiuIpUElWl0p1 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.019+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" providers=9 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.019+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" plugins=26 May 31 12:44:56 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:56 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.031+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" state=STATUS_STOPPED positionMs=0 volume=100 May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.21:54145 @ 0x20fdb60" id= title= May 31 12:44:56 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:56.061+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.178.21:54145 @ 0x20fdb60" latency=9.210805ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE May 31 12:44:57 rivoplus sudo[3944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:44:57 rivoplus sudo[3944]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:57 rivoplus sudo[3944]: pam_unix(sudo:session): session closed for user root May 31 12:44:57 rivoplus sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:44:57 rivoplus sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:57 rivoplus sudo[3946]: pam_unix(sudo:session): session closed for user root May 31 12:44:57 rivoplus volumio[3291]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 May 31 12:44:57 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:44:58 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:58 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: Listing playlists May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:44:58 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:44:58.157+02:00 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 May 31 12:44:58 rivoplus sudo[3952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:44:58 rivoplus sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:58 rivoplus sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:44:58 rivoplus sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:44:58 rivoplus sudo[3952]: pam_unix(sudo:session): session closed for user root May 31 12:44:58 rivoplus sudo[3954]: pam_unix(sudo:session): session closed for user root May 31 12:44:58 rivoplus volumio[3291]: verbose: New Socket.io Connection to 192.168.178.45 from 192.168.178.21 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 6 May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 31 12:44:58 rivoplus dbus-daemon[2827]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3893 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3202 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetVisibleSources May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 31 12:44:58 rivoplus volumio[3291]: info: Received Get System Info May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:44:58 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:58 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:44:58 rivoplus volumio[3291]: info: Listing playlists May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:44:58 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 31 12:44:58 rivoplus dbus-daemon[2827]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3893 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3202 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") May 31 12:44:59 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:44:59 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:45:00 rivoplus volumio[3291]: info: Received Get System Info May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:45:00 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:45:00 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:45:00 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:45:03 rivoplus volumio[3291]: info: Received Get System Info May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:45:03 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:45:03 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 12:45:03 rivoplus volumio[3291]: info: Received Get System Info May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:45:03 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:45:03 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:45:03 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:45:04 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetQueue May 31 12:45:04 rivoplus volumio[3291]: info: CoreStateMachine::getQueue May 31 12:45:04 rivoplus volumio[3291]: info: CorePlayQueue::getQueue May 31 12:45:05 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:45:05.358+02:00 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 May 31 12:45:05 rivoplus dbus-daemon[2827]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3893 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3202 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") May 31 12:45:06 rivoplus dbus-daemon[2827]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3893 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3202 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") May 31 12:45:08 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 31 12:45:17 rivoplus volumio[3291]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 31 12:45:17 rivoplus volumio[3291]: info: Received Get System Version May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:45:17 rivoplus volumio[3291]: info: Received Get System Info May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:45:17 rivoplus volumio[3291]: info: Discovery: Getting this device information May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::volumioGetState May 31 12:45:17 rivoplus volumio[3291]: info: CorePlayQueue::getTrack 0 May 31 12:45:17 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:45:19 rivoplus volumio5-onboarding[3893]: time=2026-05-31T12:45:19.097+02:00 level=INFO msg="new address was allocated" component=ble/conn old=3 new=4 May 31 12:45:19 rivoplus dbus-daemon[2827]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.16" (uid=0 pid=3893 comm="/usr/bin/volumio5-onboarding " label="kernel") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.5" (uid=0 pid=3202 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn" label="kernel") May 31 12:45:20 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 31 12:45:21 rivoplus volumio[3291]: info: browseTIDALUri took 1269 milliseconds May 31 12:45:21 rivoplus volumio[3291]: info: Preload queue cleared May 31 12:45:23 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 31 12:45:23 rivoplus volumio[3291]: info: browseTIDALUri took 282 milliseconds May 31 12:45:23 rivoplus volumio[3291]: info: Preload queue cleared May 31 12:45:25 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri May 31 12:45:25 rivoplus volumio[3291]: info: browseTIDALUri took 618 milliseconds May 31 12:45:25 rivoplus volumio[3291]: info: Preload queue cleared May 31 12:45:46 rivoplus volumio[3291]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch May 31 12:45:46 rivoplus volumio[3291]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:45:46 rivoplus volumio[3291]: TypeError: Cannot read property 'then' of undefined May 31 12:45:46 rivoplus volumio[3291]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:498:20) May 31 12:45:46 rivoplus volumio[3291]: at Socket.emit (events.js:400:28) May 31 12:45:46 rivoplus volumio[3291]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 May 31 12:45:46 rivoplus volumio[3291]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 31 12:45:46 rivoplus volumio[3291]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:45:46 rivoplus sudo[4093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 12:44 May 31 12:45:46 rivoplus sudo[4093]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 11:19:27 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="a7285124ffc88c43265bb2db03526f6a"