-- Logs begin at Wed 2025-03-12 21:16:36 CET, end at Wed 2025-03-12 21:18:57 CET. -- Mar 12 21:17:00 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:00 rivoplus volumio[3293]: info: ----- Volumio3 ---- Mar 12 21:17:00 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:00 rivoplus volumio[3293]: info: ----- System startup ---- Mar 12 21:17:00 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:00 rivoplus ntpd[3280]: Soliciting pool server 185.19.184.35 Mar 12 21:17:00 rivoplus ntpd[3280]: Soliciting pool server 162.159.200.123 Mar 12 21:17:00 rivoplus ntpd[3280]: Soliciting pool server 93.94.88.50 Mar 12 21:17:00 rivoplus ntpd[3280]: Soliciting pool server 185.157.229.254 Mar 12 21:17:00 rivoplus volumio-time-update[2839]: volumio-time-update-util: Fetching time from Volumio... Mar 12 21:17:00 rivoplus volumio-time-update[2839]: volumio-time-update-util: Setting system time to: 2025-03-12 21:17:01 Mar 12 21:17:00 rivoplus sudo[3419]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-03-12 21:17:01 Mar 12 21:17:00 rivoplus sudo[3419]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:00 rivoplus dbus-daemon[2833]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=3420 comm="timedatectl set-time 2025-03-12 21:17:01 " label="kernel") Mar 12 21:17:01 rivoplus systemd[1]: Starting Time & Date Service... Mar 12 21:17:01 rivoplus dbus-daemon[2833]: [system] Successfully activated service 'org.freedesktop.timedate1' Mar 12 21:17:01 rivoplus systemd[1]: Started Time & Date Service. Mar 12 21:17:01 rivoplus systemd-timedated[3421]: Changed local time to Wed Mar 12 21:17:01 2025 Mar 12 21:17:01 rivoplus sudo[3419]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:01 rivoplus volumio-time-update[2839]: volumio-time-update-util: System time updated successfully. Mar 12 21:17:01 rivoplus systemd[1]: Started Volumio Time Update Utility. Mar 12 21:17:01 rivoplus systemd[1]: Reached target Multi-User System. Mar 12 21:17:01 rivoplus systemd[1]: Reached target Graphical Interface. Mar 12 21:17:01 rivoplus systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 12 21:17:01 rivoplus systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 12 21:17:01 rivoplus systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 12 21:17:01 rivoplus systemd[1]: Startup finished in 12.913s (kernel) + 25.164s (userspace) = 38.078s. Mar 12 21:17:01 rivoplus ntpd[3280]: Soliciting pool server 37.247.53.178 Mar 12 21:17:01 rivoplus ntpd[3280]: Soliciting pool server 93.94.88.51 Mar 12 21:17:01 rivoplus ntpd[3280]: Soliciting pool server 95.110.254.234 Mar 12 21:17:01 rivoplus volumio[3293]: info: MYVOLUMIO Environment detected Mar 12 21:17:01 rivoplus volumio[3293]: info: Plugin folders cleanup Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning into folder /volumio/app/plugins/ Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category audio_interface Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category miscellanea Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category music_service Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category plugins.json Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category system_controller Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category user_interface Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning into folder /data/plugins/ Mar 12 21:17:01 rivoplus volumio[3293]: info: Scanning category music_service Mar 12 21:17:01 rivoplus volumio[3293]: info: Plugin folders cleanup completed Mar 12 21:17:01 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:01 rivoplus volumio[3293]: info: ----- Core plugins startup ---- Mar 12 21:17:01 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:01 rivoplus volumio[3293]: info: Loading plugins from folder /volumio/app/plugins/ Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin bluetooth to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin multiroom to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin upnp to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin metavolumio to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin cd_controller to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin raat to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 12 21:17:01 rivoplus volumio[3293]: info: Loading plugins from folder /data/plugins/ Mar 12 21:17:01 rivoplus volumio[3293]: info: Loading plugin "system"... Mar 12 21:17:01 rivoplus volumio[3293]: info: Loading plugin "appearance"... Mar 12 21:17:02 rivoplus ntpd[3280]: Soliciting pool server 5.249.144.95 Mar 12 21:17:02 rivoplus ntpd[3280]: Soliciting pool server 89.46.74.148 Mar 12 21:17:03 rivoplus ntpd[3280]: Soliciting pool server 45.135.69.197 Mar 12 21:17:03 rivoplus ntpd[3280]: Soliciting pool server 2a03:b020:0:404::51 Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "network"... Mar 12 21:17:03 rivoplus volumio[3293]: info: Refreshing Cached IP Addresses Mar 12 21:17:03 rivoplus sudo[3430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 21:17:03 rivoplus sudo[3430]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:03 rivoplus sudo[3432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 21:17:03 rivoplus sudo[3432]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:03 rivoplus sudo[3430]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "services"... Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "alsa_controller"... Mar 12 21:17:03 rivoplus sudo[3432]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:03 rivoplus sudo[3440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 12 21:17:03 rivoplus sudo[3440]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:03 rivoplus kernel: wl_escan_set_scan: ioctl_ver=2 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "wizard"... Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "networkfs"... Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus volumio[3293]: info: Starting Udev Watcher for removable devices Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus volumio[3293]: info: Ignoring mount for partition: BOOT Mar 12 21:17:03 rivoplus volumio[3293]: info: Ignoring mount for partition: volumio Mar 12 21:17:03 rivoplus volumio[3293]: info: Ignoring mount for partition: volumio_data Mar 12 21:17:03 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "volumio_command_line_client"... Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "upnp"... Mar 12 21:17:03 rivoplus volumio[3293]: info: [1741810623643] Starting Upmpd Daemon Mar 12 21:17:03 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "my_music"... Mar 12 21:17:03 rivoplus volumio[3293]: info: Loading plugin "mpd"... Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:03 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus volumio-remote-updater[2841]: [2025-03-12 21:17:04] [connect] Successful connection Mar 12 21:17:04 rivoplus volumio[3293]: info: Loading plugin "upnp_browser"... Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:04 rivoplus volumio[3293]: info: Loading plugin "bluetooth"... Mar 12 21:17:04 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:05 rivoplus volumio[3293]: info: [1741810625019] Starting BluetoothController Mar 12 21:17:05 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:05 rivoplus volumio[3293]: info: Loading plugin "alarm-clock"... Mar 12 21:17:05 rivoplus volumio[3293]: info: Loading plugin "manifestui"... Mar 12 21:17:05 rivoplus volumio[3293]: info: Loading plugin "metavolumio"... Mar 12 21:17:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:06 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:07 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xeb7c6a42.1cba3494 does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xeb7c6a43.3e83ea7d Mar 12 21:17:07 rivoplus ntpd[3280]: receive: Unexpected origin timestamp 0xeb7c6a42.1cb24a39 does not match aorg 0000000000.00000000 from server@212.6.50.243 xmt 0xeb7c6a43.42643baa Mar 12 21:17:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:07 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:08 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 8 Mar 12 21:17:08 rivoplus kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04df80000 drv_status 0x0 e_type 69 e_states 0 Mar 12 21:17:08 rivoplus sudo[3440]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:08 rivoplus volumio[3293]: info: Loading plugin "airplay_emulation"... Mar 12 21:17:08 rivoplus volumio[3293]: info: Starting Shairport Sync Mar 12 21:17:08 rivoplus volumio[3293]: info: Loading plugin "cd_controller"... Mar 12 21:17:09 rivoplus volumio[3293]: info: Loading plugin "last_100"... Mar 12 21:17:09 rivoplus volumio[3293]: info: Loading plugin "raat"... Mar 12 21:17:09 rivoplus volumio[3293]: info: RAAT Plugin loaded Mar 12 21:17:09 rivoplus volumio[3293]: info: Adding restartRAATSocket REST API Endpoint Mar 12 21:17:09 rivoplus volumio[3293]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Mar 12 21:17:09 rivoplus volumio[3293]: info: Loading plugin "streaming_services"... Mar 12 21:17:10 rivoplus systemd[1]: systemd-fsckd.service: Succeeded. Mar 12 21:17:11 rivoplus volumio[3293]: info: Starting Streaming Service Transparent Proxy Mar 12 21:17:11 rivoplus volumio[3293]: info: Loading plugin "tidalconnect"... Mar 12 21:17:11 rivoplus volumio[3293]: info: Loading plugin "webradio"... Mar 12 21:17:12 rivoplus volumio[3293]: info: Loading plugin "i2s_dacs"... Mar 12 21:17:12 rivoplus volumio[3293]: info: I2S DAC not set, start Auto-detection Mar 12 21:17:12 rivoplus volumio[3293]: info: Loading plugin "volumiodiscovery"... Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** For more information see Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 21:17:12 rivoplus volumio[3293]: *** WARNING *** For more information see Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** For more information see Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 12 21:17:12 rivoplus node[3293]: *** WARNING *** For more information see Mar 12 21:17:12 rivoplus volumio[3293]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 12 21:17:12 rivoplus volumio[3293]: info: Discovery: Started advertising with name: Rivoplus Mar 12 21:17:12 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 21:17:12 rivoplus volumio[3293]: info: Plugin mixcloud is not enabled Mar 12 21:17:12 rivoplus volumio[3293]: info: Loading plugin "multiroom"... Mar 12 21:17:12 rivoplus sh[2761]: timed out Mar 12 21:17:12 rivoplus sh[2761]: dhcpcd exited Mar 12 21:17:12 rivoplus dhcpcd[2807]: timed out Mar 12 21:17:12 rivoplus dhcpcd[2807]: dhcpcd exited Mar 12 21:17:12 rivoplus sh[2761]: ifup: failed to bring up eth0 Mar 12 21:17:12 rivoplus systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 12 21:17:12 rivoplus systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 12 21:17:13 rivoplus volumio[3293]: STREAMING PROXY: Starting server on port 3245 Mar 12 21:17:13 rivoplus volumio[3293]: Node JS runtime: 14 Mar 12 21:17:15 rivoplus volumio[3293]: info: Applying required configuration parameters for plugin multiroom Mar 12 21:17:15 rivoplus sudo[3653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 12 21:17:15 rivoplus sudo[3653]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:15 rivoplus sudo[3653]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:15 rivoplus volumio[3293]: info: MRS: MultiRoom plugin initialized Mar 12 21:17:15 rivoplus volumio[3293]: info: MRS: STOPPING SNAPCLIENT Mar 12 21:17:15 rivoplus volumio[3293]: info: MRS: Snap server stop Mar 12 21:17:15 rivoplus volumio[3293]: info: MRS: STOPPING volumioStreaming Mar 12 21:17:15 rivoplus sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 12 21:17:15 rivoplus sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:15 rivoplus sudo[3671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 12 21:17:15 rivoplus sudo[3671]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:15 rivoplus volumio[3293]: info: Loading plugin "outputs"... Mar 12 21:17:15 rivoplus sudo[3673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 12 21:17:15 rivoplus sudo[3673]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:15 rivoplus volumio[3293]: info: Loading plugin "albumart"... Mar 12 21:17:15 rivoplus sudo[3677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 12 21:17:15 rivoplus sudo[3677]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:15 rivoplus sudo[3677]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:15 rivoplus volumio[3293]: info: Plugin example_plugin is not enabled Mar 12 21:17:15 rivoplus volumio[3293]: info: Loading plugin "hi_res_audio"... Mar 12 21:17:15 rivoplus sudo[3669]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:15 rivoplus sudo[3671]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:15 rivoplus sudo[3673]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:16 rivoplus volumio[3293]: Forking 3 albumart workers Mar 12 21:17:18 rivoplus volumio[3293]: Starting albumart workers Mar 12 21:17:18 rivoplus volumio[3293]: Starting albumart workers Mar 12 21:17:18 rivoplus volumio[3293]: Starting albumart workers Mar 12 21:17:19 rivoplus volumio[3293]: info: Applying required configuration parameters for plugin hi_res_audio Mar 12 21:17:19 rivoplus volumio[3293]: info: Loading plugin "inputs"... Mar 12 21:17:19 rivoplus volumio[3293]: info: Loading plugin "qobuz"... Mar 12 21:17:20 rivoplus volumio-remote-updater[2841]: [2025-03-12 21:17:20] [connect] Successful connection Mar 12 21:17:21 rivoplus volumio[3293]: info: Loading plugin "smart_inputs"... Mar 12 21:17:22 rivoplus volumio[3293]: info: Loading plugin "tidal"... Mar 12 21:17:22 rivoplus systemd[1]: systemd-hostnamed.service: Succeeded. Mar 12 21:17:24 rivoplus volumio[3293]: info: Loading plugin "rivopluscontrol"... Mar 12 21:17:24 rivoplus volumio[3293]: info: Adding this device properties Mar 12 21:17:24 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Mar 12 21:17:24 rivoplus volumio[3293]: info: Setting Additional Device Volumio Properties: [object Object] Mar 12 21:17:24 rivoplus volumio[3293]: info: Loading plugin "updater_comm"... Mar 12 21:17:24 rivoplus volumio[3293]: info: Loading plugin "cec_controller"... Mar 12 21:17:25 rivoplus volumio[3293]: info: Applying required configuration parameters for plugin cec_controller Mar 12 21:17:25 rivoplus volumio[3293]: info: Plugin mpdemulation is not enabled Mar 12 21:17:25 rivoplus volumio[3293]: info: Loading plugin "rest_api"... Mar 12 21:17:25 rivoplus volumio[3293]: info: Loading plugin "websocket"... Mar 12 21:17:25 rivoplus volumio[3293]: info: Starting Socket.io Server version 2.3.0 Mar 12 21:17:25 rivoplus volumio[3293]: info: Loading i18n strings for locale en Mar 12 21:17:25 rivoplus volumio[3293]: Updating browse sources language Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::initPlayerControls Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 21:17:25 rivoplus volumio[3293]: Express server listening on port 3000 Mar 12 21:17:25 rivoplus volumio[3293]: [Metrics] WebUI: 25s 848.10ms Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreStateMachine::resetVolumioState Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreStateMachine::getcurrentVolume Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::volumioRetrievevolume Mar 12 21:17:25 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:25 rivoplus sudo[3759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 21:17:25 rivoplus sudo[3759]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:25 rivoplus sudo[3759]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:25 rivoplus volumio[3293]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 12 21:17:25 rivoplus volumio[3293]: info: Completed loading Core Plugins Mar 12 21:17:25 rivoplus sudo[3761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 21:17:25 rivoplus sudo[3761]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:25 rivoplus volumio[3293]: info: Preparing to generate the ALSA configuration file Mar 12 21:17:25 rivoplus sudo[3761]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:25 rivoplus volumio[3293]: info: Volumio Network Manager: Network status updated: 2 Mar 12 21:17:25 rivoplus volumio[3293]: info: MRS: Removed streaming files Mar 12 21:17:25 rivoplus volumio[3293]: info: MRS: volumioStreaming STOPPED Mar 12 21:17:25 rivoplus volumio[3293]: info: MRS: SNAPSERVER STOPPED Mar 12 21:17:25 rivoplus volumio[3293]: info: MRS: SNAPCLIENT STOPPED Mar 12 21:17:26 rivoplus volumio[3293]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 12 21:17:26 rivoplus volumio[3293]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 12 21:17:26 rivoplus volumio[3293]: info: Reading ALSA contributions from plugins. Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 12 21:17:26 rivoplus volumio[3293]: info: VolumeController:: Volume=100 Mute =false Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::updateTrackBlock Mar 12 21:17:26 rivoplus volumio[3293]: info: CorePlayQueue::getTrackBlock Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioRetrievevolume Mar 12 21:17:26 rivoplus volumio[3293]: info: Reloading queue from file Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Mar 12 21:17:26 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::setRepeat false single undefined Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::setRandom null Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:26 rivoplus kernel: fb: mem_free_work, free memory: addr:800000 Mar 12 21:17:26 rivoplus volumio[3293]: info: VolumeController:: Volume=100 Mute =false Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:26 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:26 rivoplus volumio[3293]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 12 21:17:26 rivoplus sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 12 21:17:26 rivoplus sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:26 rivoplus volumio[3293]: info: Asound.conf file unchanged, so no further update is needed Mar 12 21:17:26 rivoplus volumio[3293]: info: Output device has changed, restarting MPD Mar 12 21:17:26 rivoplus systemd[1]: Started UPnP Renderer front-end to MPD. Mar 12 21:17:26 rivoplus sudo[3780]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:26 rivoplus sudo[3785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 12 21:17:26 rivoplus sudo[3785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:26 rivoplus sudo[3785]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:26 rivoplus volumio[3293]: info: Output device has changed, restarting Shairport Sync Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:26 rivoplus sudo[3799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 12 21:17:26 rivoplus sudo[3799]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:26 rivoplus systemd[1]: Stopping Music Player Daemon... Mar 12 21:17:26 rivoplus volumio[3293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 21:17:26 rivoplus volumio[3293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:26 rivoplus volumio[3293]: info: ___________ START PLUGINS ___________ Mar 12 21:17:26 rivoplus volumio[3293]: info: ControllerMpd::onStart: Initializing MPD Mar 12 21:17:26 rivoplus volumio[3293]: info: Creating MPD Configuration file Mar 12 21:17:26 rivoplus sudo[3807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 12 21:17:26 rivoplus sudo[3807]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 21:17:26 rivoplus volumio[3293]: info: [1741810646950] CoreMusicLibrary::Adding element Media Servers Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:26 rivoplus sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 12 21:17:26 rivoplus systemd[1]: mpd.service: Succeeded. Mar 12 21:17:26 rivoplus sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:26 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 12 21:17:26 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 12 21:17:26 rivoplus sudo[3807]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:26 rivoplus systemd[1]: Starting Music Player Daemon... Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding METAVOLUMIO REST API Endpoints Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:27 rivoplus sudo[3814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivoplus Mar 12 21:17:27 rivoplus sudo[3814]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 12 21:17:27 rivoplus systemd[1]: mpd.service: Succeeded. Mar 12 21:17:27 rivoplus systemd[1]: Stopped Music Player Daemon. Mar 12 21:17:27 rivoplus sudo[3814]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus systemd[1]: Starting Music Player Daemon... Mar 12 21:17:27 rivoplus volumio[3293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 12 21:17:27 rivoplus volumio[3293]: info: Preparing CD Folders Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding CD REST API Endpoints Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 12 21:17:27 rivoplus volumio[3293]: info: Starting UDEV Watcher for CD Mar 12 21:17:27 rivoplus volumio[3293]: info: Detecting CD presence with UDEV Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 12 21:17:27 rivoplus sudo[3817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 12 21:17:27 rivoplus sudo[3817]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 21:17:27 rivoplus volumio[3293]: info: [1741810647207] CoreMusicLibrary::Adding element Last_100 Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:27 rivoplus volumio[3293]: info: Starting RAAT Plugin Mar 12 21:17:27 rivoplus sudo[3817]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 12 21:17:27 rivoplus volumio[3293]: info: Additional UI Settings Added for plugin music_service/raat Mar 12 21:17:27 rivoplus volumio[3293]: info: Registering DSP Elements listener and retrieving current ones Mar 12 21:17:27 rivoplus volumio[3293]: info: Additional DSP elements updated Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: Updating RAAT Signal Path Mar 12 21:17:27 rivoplus volumio[3293]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Mar 12 21:17:27 rivoplus volumio[3293]: info: Streaming services startup Mar 12 21:17:27 rivoplus volumio[3293]: info: Starting Streaming Daemon Mar 12 21:17:27 rivoplus sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 12 21:17:27 rivoplus sudo[3828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3828]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 21:17:27 rivoplus volumio[3293]: info: [1741810647392] CoreMusicLibrary::Adding element Webradio Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:27 rivoplus volumio[3293]: info: Initializing BBC Radios Mar 12 21:17:27 rivoplus sudo[3837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Mar 12 21:17:27 rivoplus sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3837]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 12 21:17:27 rivoplus volumio[3293]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 12 21:17:27 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:27 rivoplus volumio[3293]: error: Hi Res Audio Failed Login: Missing Login Data Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 12 21:17:27 rivoplus volumio[3293]: info: Initializing Serial Communication on port /dev/ttyS3 Mar 12 21:17:27 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Mar 12 21:17:27 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Mar 12 21:17:27 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Mar 12 21:17:27 rivoplus volumio[3293]: info: Touch Event Listener Process Starting Mar 12 21:17:27 rivoplus volumio[3293]: info: Refreshing QOBUZ token Mar 12 21:17:27 rivoplus sudo[3854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Mar 12 21:17:27 rivoplus sudo[3854]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding inputs REST Endpoints Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 12 21:17:27 rivoplus volumio[3293]: info: Scanning Audio Inputs Mar 12 21:17:27 rivoplus volumio[3293]: info: Checking against Known Cards name Mar 12 21:17:27 rivoplus volumio[3293]: info: Checking against Known Cards name Mar 12 21:17:27 rivoplus volumio[3293]: info: Checking against Known Cards name Mar 12 21:17:27 rivoplus volumio[3293]: info: Checking against Known Cards name Mar 12 21:17:27 rivoplus volumio[3293]: info: Checking against Known Cards name Mar 12 21:17:27 rivoplus volumio[3293]: info: Adding Server instance for streaming Mar 12 21:17:27 rivoplus sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Mar 12 21:17:27 rivoplus sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3864]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus sudo[3867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Mar 12 21:17:27 rivoplus sudo[3867]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3867]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus sudo[3870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Mar 12 21:17:27 rivoplus sudo[3870]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3870]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus sudo[3873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Mar 12 21:17:27 rivoplus sudo[3873]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:27 rivoplus sudo[3873]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:27 rivoplus volumio[3293]: info: Apply VIM3L Onboard LEDs Settings Mar 12 21:17:27 rivoplus volumio[3293]: info: Turning On Onboard LEDs Mar 12 21:17:28 rivoplus sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 12 21:17:28 rivoplus sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:28 rivoplus volumio[3293]: info: Starting CEC Event listener Mar 12 21:17:28 rivoplus volumio[3293]: info: Volumio Calling Home Mar 12 21:17:28 rivoplus sudo[3877]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:28 rivoplus sudo[3881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 12 21:17:28 rivoplus sudo[3881]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:28 rivoplus sudo[3881]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:28 rivoplus volumio[3293]: (node:3293) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Mar 12 21:17:28 rivoplus volumio[3293]: (Use `node --trace-deprecation ...` to show where the warning was created) Mar 12 21:17:28 rivoplus volumio[3293]: info: Stopping AccessToken refresher cron Mar 12 21:17:28 rivoplus volumio[3293]: info: AccessToken refresher cron started Mar 12 21:17:28 rivoplus volumio[3293]: info: Adding TIDAL REST API Endpoints Mar 12 21:17:28 rivoplus volumio[3293]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 12 21:17:28 rivoplus volumio[3293]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Mar 12 21:17:28 rivoplus volumio[3293]: info: Serial port opened successfully Mar 12 21:17:28 rivoplus volumio[3293]: info: Sending serial start messages Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: Reporting MCU Network Status: 2 Mar 12 21:17:28 rivoplus volumio[3293]: error: Cannot start Volumio Streaming Daemon Mar 12 21:17:28 rivoplus volumio[3293]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 12 21:17:28 rivoplus volumio[3293]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 12 21:17:28 rivoplus volumio[3293]: info: RAAT Albumart path created successfully Mar 12 21:17:28 rivoplus volumio[3293]: info: Bluetooth name changed to Rivoplus Mar 12 21:17:28 rivoplus volumio[3293]: info: MPD Permissions set Mar 12 21:17:28 rivoplus volumio[3293]: info: MPD Permissions set Mar 12 21:17:28 rivoplus volumio[3293]: info: Upmpdcli Daemon Started Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting Device Volume Override Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 12 21:17:28 rivoplus volumio[3293]: info: Updating Volume Controller Parameters: Device: 5 Name: UAC2 Audio Volumio Preciso Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 12 21:17:28 rivoplus volumio[3293]: info: Disabling external Volume Control Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:28 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:28 rivoplus volumio[3293]: info: Volumio called home Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting Additional System Software info: Hardware Revision: 1.0 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting HW Firmware info: undefined Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting HW Version info: 1.0 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.6 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting HW Firmware info: 0.2.6 Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 21:17:28 rivoplus volumio[3293]: info: Setting HW Version info: 1.0 Mar 12 21:17:28 rivoplus volumio[3293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 12 21:17:28 rivoplus volumio[3293]: info: MCU Signalled Headphone Mode Disabled Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 12 21:17:28 rivoplus volumio[3293]: info: Reporting Headphone State: false Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: Updating RAAT Signal Path Mar 12 21:17:28 rivoplus volumio[3293]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Mar 12 21:17:28 rivoplus volumio[3293]: info: MCU Signalled Sleep Mode Disabled Mar 12 21:17:28 rivoplus volumio[3293]: info: Enabling Advanced system settings configuration Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Mar 12 21:17:28 rivoplus volumio[3293]: info: Additional UI Settings Added for plugin music_service/inputs Mar 12 21:17:28 rivoplus volumio[3293]: info: MCU Signalled Auto Boot Mode On Power Disabled Mar 12 21:17:28 rivoplus sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Mar 12 21:17:28 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Mar 12 21:17:28 rivoplus sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:28 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Mar 12 21:17:28 rivoplus sudo[3891]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:28 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:29 rivoplus volumio[3293]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Mar 12 21:17:29 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Mar 12 21:17:29 rivoplus sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Mar 12 21:17:29 rivoplus sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:29 rivoplus sudo[3912]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus volumio[3293]: info: Access Token successfully retrieved Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 12 21:17:29 rivoplus volumio[3293]: info: [1741810649261] CoreMusicLibrary::Adding element QOBUZ Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:29 rivoplus volumio[3293]: Cannot find translation for source QOBUZ Mar 12 21:17:29 rivoplus volumio[3293]: info: Stopping AccessToken refresher cron for QOBUZ Mar 12 21:17:29 rivoplus mpd[3826]: Mar 12 21:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 12 21:17:29 rivoplus systemd[1]: Started Music Player Daemon. Mar 12 21:17:29 rivoplus sudo[3799]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus sudo[3809]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus volumio[3293]: info: AccessToken refresher cron started for QOBUZ Mar 12 21:17:29 rivoplus volumio[3293]: info: Adding QOBUZ REST API Endpoints Mar 12 21:17:29 rivoplus volumio[3293]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 12 21:17:29 rivoplus volumio[3293]: info: VolumeController:: Volume=100 Mute =false Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:29 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:29 rivoplus volumio[3293]: info: MP1 GPIO: Signalled system ready via GPIO Mar 12 21:17:29 rivoplus volumio[3293]: Upnp client error: Error: This socket has been ended by the other party Mar 12 21:17:29 rivoplus volumio[3293]: info: Starting Shairport Sync Mar 12 21:17:29 rivoplus volumio[3293]: info: Starting Shairport Sync Mar 12 21:17:29 rivoplus sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 21:17:29 rivoplus sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:29 rivoplus volumio[3293]: info: Starting Shairport Sync Mar 12 21:17:29 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 21:17:29 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 21:17:29 rivoplus sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 21:17:29 rivoplus sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:29 rivoplus volumio[3293]: info: Adding Inputs via Serial API Mar 12 21:17:29 rivoplus volumio[3293]: info: Adding Advanced Audio Settings via Serial API Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 12 21:17:29 rivoplus volumio[3293]: info: Additional UI Settings Added for plugin music_service/inputs Mar 12 21:17:29 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus sudo[3922]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus sudo[3929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 12 21:17:29 rivoplus sudo[3929]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:29 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:29 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 21:17:29 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 12 21:17:29 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 21:17:29 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:29 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:29 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:29 rivoplus sudo[3926]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 12 21:17:29 rivoplus systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 12 21:17:29 rivoplus systemd[1]: shairport-sync.service: Succeeded. Mar 12 21:17:29 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 12 21:17:29 rivoplus sudo[3929]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:29 rivoplus volumio[3293]: error: MPD error: The expression evaluated to a falsy value: Mar 12 21:17:29 rivoplus volumio[3293]: assert.ok(self.idling) Mar 12 21:17:29 rivoplus volumio[3293]: error: The expression evaluated to a falsy value: Mar 12 21:17:29 rivoplus volumio[3293]: assert.ok(self.idling) Mar 12 21:17:29 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:29 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:29 rivoplus volumio[3293]: info: Shairport-Sync Started Mar 12 21:17:29 rivoplus volumio[3293]: Error adding Membership: Error: addMembership EINVAL Mar 12 21:17:29 rivoplus volumio[3293]: info: Shairport-Sync Started Mar 12 21:17:29 rivoplus volumio[3293]: info: MPD running with PID3826 Mar 12 21:17:29 rivoplus volumio[3293]: ,establishing connection Mar 12 21:17:29 rivoplus volumio[3293]: error: updateQueue error: null Mar 12 21:17:29 rivoplus volumio[3293]: info: Shairport-Sync Started Mar 12 21:17:30 rivoplus volumio[3293]: error: updateQueue error: null Mar 12 21:17:30 rivoplus sudo[3940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 12 21:17:30 rivoplus sudo[3940]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:30 rivoplus systemd[1]: Started Volumio Bluetooth Module. Mar 12 21:17:30 rivoplus sudo[3940]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:30 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:30 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:30 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Mar 12 21:17:30 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:30 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService play Mar 12 21:17:30 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:30 rivoplus volumio[3293]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 12 21:17:30 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:30 rivoplus volumio[3293]: Cannot find translation for source QOBUZ Mar 12 21:17:30 rivoplus sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 12 21:17:30 rivoplus sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:30 rivoplus volumiobt[3942]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 12 21:17:30 rivoplus sudo[3943]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:30 rivoplus volumio[3293]: info: Volumio BT Module successfully started Mar 12 21:17:30 rivoplus volumio[3293]: info: TidalConnect service stoped! Mar 12 21:17:30 rivoplus volumio[3293]: info: Turning Off Onboard LEDs Mar 12 21:17:30 rivoplus volumio[3293]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Mar 12 21:17:30 rivoplus volumio[3293]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Mar 12 21:17:30 rivoplus sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 12 21:17:30 rivoplus sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:30 rivoplus sudo[3956]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:30 rivoplus sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 12 21:17:30 rivoplus sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:30 rivoplus sudo[3961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Mar 12 21:17:30 rivoplus sudo[3960]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:30 rivoplus sudo[3961]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:30 rivoplus volumio[3293]: info: Onboard LEDs initialized Mar 12 21:17:30 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Mar 12 21:17:30 rivoplus sudo[3961]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:30 rivoplus volumio[3784]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 12 21:17:31 rivoplus volumio[3293]: info: Executing endpoint tc_getconfig Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Mar 12 21:17:31 rivoplus vtcs[3964]: STARTING TidalConnect services, version: 1.4.0.34 Mar 12 21:17:31 rivoplus sudo[3983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 12 21:17:31 rivoplus sudo[3983]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:31 rivoplus volumiobt[3942]: pulseaudio: no process found Mar 12 21:17:31 rivoplus sudo[3983]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:31 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:31 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:31 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:31 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:31 rivoplus dbus-daemon[2833]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=3987 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:31 rivoplus vtcs[3964]: STARTED TidalConnect services. Mar 12 21:17:31 rivoplus systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 12 21:17:31 rivoplus kernel: IPVS: Creating netns size=1928 id=3 Mar 12 21:17:31 rivoplus dbus-daemon[2833]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 12 21:17:31 rivoplus systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Successfully called chroot. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Successfully dropped privileges. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Successfully limited resources. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Running. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Watchdog thread running. Mar 12 21:17:31 rivoplus rtkit-daemon[3988]: Canary thread running. Mar 12 21:17:31 rivoplus dbus-daemon[2833]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=3988 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Mar 12 21:17:31 rivoplus systemd[1]: Starting Authorization Manager... Mar 12 21:17:31 rivoplus volumio[3293]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 12 21:17:31 rivoplus volumio[3293]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 12 21:17:31 rivoplus volumio[3293]: info: Executing endpoint tc_connect Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Mar 12 21:17:31 rivoplus volumio[3293]: info: Connecting to TidalConnect Mar 12 21:17:31 rivoplus polkitd[3998]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:31 rivoplus dbus-daemon[2833]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 12 21:17:31 rivoplus systemd[1]: Started Authorization Manager. Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: 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} Mar 12 21:17:31 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus play Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::stPlaybackTimer Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: 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} Mar 12 21:17:31 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:31 rivoplus volumio[3293]: info: No code Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:31 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:31 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:32 rivoplus systemd[1]: systemd-timedated.service: Succeeded. Mar 12 21:17:32 rivoplus pulseaudio[3987]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 12 21:17:32 rivoplus pulseaudio[3987]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 12 21:17:32 rivoplus volumiobt[3942]: Applying permissions Mar 12 21:17:32 rivoplus volumiobt[3942]: Setting BT discoverable and pairable Mar 12 21:17:32 rivoplus bluetoothd[3210]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Mar 12 21:17:32 rivoplus bluetoothd[3210]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Mar 12 21:17:32 rivoplus pulseaudio[3987]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Mar 12 21:17:32 rivoplus volumiobt[3942]: Agent registered Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# -e power on Mar 12 21:17:32 rivoplus volumiobt[3942]: Invalid command in menu main: -e Mar 12 21:17:32 rivoplus volumiobt[3942]: Mar 12 21:17:32 rivoplus volumiobt[3942]: Use "help" for a list of available commands in a menu. Mar 12 21:17:32 rivoplus volumiobt[3942]: Use "menu " if you want to enter any submenu. Mar 12 21:17:32 rivoplus volumiobt[3942]: Use "back" if you want to return to menu main. Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# agent on Mar 12 21:17:32 rivoplus volumiobt[3942]: Agent is already registered Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# discoverable on Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# pairable on Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# agent NoInputNoOutput Mar 12 21:17:32 rivoplus volumiobt[3942]: Agent is already registered Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# default-agent Mar 12 21:17:32 rivoplus volumiobt[3942]: [bluetooth]# quit Mar 12 21:17:32 rivoplus volumiobt[3942]: [59B blob data] Mar 12 21:17:32 rivoplus volumio[3293]: info: MRS: Getting audio outputs on start Mar 12 21:17:32 rivoplus volumio[3293]: info: MRS: Requesting all other devices output Mar 12 21:17:33 rivoplus volumio[3293]: info: TidalConnect service started! Mar 12 21:17:33 rivoplus volumio[3293]: info: Completed starting Core Plugins Mar 12 21:17:33 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:33 rivoplus volumio[3293]: info: ----- MyVolumio plugins startup ---- Mar 12 21:17:33 rivoplus volumio[3293]: info: ------------------------------------------- Mar 12 21:17:33 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 12 21:17:33 rivoplus volumio[3293]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 20 Mar 12 21:17:34 rivoplus volumio[3784]: .....................................++++ Mar 12 21:17:35 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:35 rivoplus volumio-remote-updater[2841]: [2025-03-12 21:17:35] [connect] Successful connection Mar 12 21:17:35 rivoplus volumio-remote-updater[2841]: [2025-03-12 21:17:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1741810655 101 Mar 12 21:17:35 rivoplus volumio[3293]: 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: 21 Mar 12 21:17:36 rivoplus volumio[3293]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 22 Mar 12 21:17:38 rivoplus volumio[3293]: info: Checking for updated MCU Firmware Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:38 rivoplus volumio[3293]: info: Found new update for firmware, current version: 0.2.6 new version: 0.2.7 Mar 12 21:17:38 rivoplus volumio[3293]: info: Starting Firmware Update for MCU Mar 12 21:17:38 rivoplus volumio[3293]: info: Setting MCU in bootloader mode Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: inputs , setBootloaderMode Mar 12 21:17:38 rivoplus volumio[3293]: info: Waiting 10 seconds to start new firmware update Mar 12 21:17:38 rivoplus volumio[3293]: info: MCU Signalled Bootloader Mode Active Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: USB disconnect, device number 4 Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: new full-speed USB device number 7 using xhci-hcd Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: New USB device found, idVendor=2e8a, idProduct=0003 Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: Product: RP2 Boot Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: Manufacturer: Raspberry Pi Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: SerialNumber: E0C9125B0D9B Mar 12 21:17:38 rivoplus kernel: usb-storage 1-1.4.1:1.0: USB Mass Storage device detected Mar 12 21:17:38 rivoplus kernel: scsi host0: usb-storage 1-1.4.1:1.0 Mar 12 21:17:38 rivoplus kernel: usb 1-1.4.1: Unsupported device Mar 12 21:17:38 rivoplus volumio[3293]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 23 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:38 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:38 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:38 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:17:38 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:38 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:38 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:38 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:38 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:38 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:38 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:38 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:39 rivoplus kernel: scsi 0:0:0:0: Direct-Access RPI RP2 3 PQ: 0 ANSI: 2 Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] 262144 512-byte logical blocks: (134 MB/128 MiB) Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] No Caching mode page found Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Mar 12 21:17:39 rivoplus kernel: sda: sda1 Mar 12 21:17:39 rivoplus kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:17:40 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:40 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:40 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:40 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:41 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 12 21:17:41 rivoplus volumio[3293]: info: Mounting Device RPI-RP2 Mar 12 21:17:42 rivoplus sudo[4098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/RPI-RP2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 12 21:17:42 rivoplus sudo[4098]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:43 rivoplus kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Mar 12 21:17:43 rivoplus sudo[4098]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 12 21:17:43 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 12 21:17:44 rivoplus volumio[3293]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 12 21:17:44 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 12 21:17:44 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:44 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:44 rivoplus volumio[3293]: info: Starting MyVolumio Remote Streaming Endpoints Mar 12 21:17:44 rivoplus volumio[3293]: info: MyVolumio login type: Token Mar 12 21:17:44 rivoplus volumio[3293]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 12 21:17:44 rivoplus volumio[3293]: 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' Mar 12 21:17:44 rivoplus volumio[3293]: info: Scanning new location : "USB/RPI-RP2" Mar 12 21:17:44 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:44 rivoplus volumio[3293]: info: Mar 12 21:17:44 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:44 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:44 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:44 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:44 rivoplus volumio[3293]: info: Mar 12 21:17:44 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: Mar 12 21:17:45 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: Mar 12 21:17:45 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 30 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 26 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 25 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 16 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 14 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 13 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatetrue Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatetrue Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 405ms Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 401ms Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 388ms Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: Mar 12 21:17:45 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: Mar 12 21:17:45 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 397 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 392 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 15 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 14 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 12 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: info: sendMpdCommand status took 10 milliseconds Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:45 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:17:45 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: No code Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:17:45 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:45 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 738ms Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 358ms Mar 12 21:17:45 rivoplus volumio[3293]: info: ------------------------------ 356ms Mar 12 21:17:46 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 12 21:17:46 rivoplus volumio[3293]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 12 21:17:47 rivoplus volumio[3293]: info: MyVolumio token set successfully Mar 12 21:17:47 rivoplus volumio[3293]: info: MYVOLUMIO: Adding device Mar 12 21:17:47 rivoplus volumio[3293]: info: MYVOLUMIO: Evaluating Server Mar 12 21:17:47 rivoplus volumio[3293]: info: MyVolumio Plan changed: premium Mar 12 21:17:47 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 12 21:17:47 rivoplus volumio[3293]: info: Removing browser output: myVolumio user plan is not superstar Mar 12 21:17:47 rivoplus volumio[3293]: info: Removing audio output: Mar 12 21:17:47 rivoplus volumio[3293]: info: MYVOLUMIO: Adding device Mar 12 21:17:47 rivoplus volumio[3293]: info: MYVOLUMIO: Evaluating Server Mar 12 21:17:47 rivoplus volumio[3293]: info: Remote config written successfully Mar 12 21:17:47 rivoplus volumio[3293]: info: Starting Tunnel 1 Mar 12 21:17:47 rivoplus volumio[3293]: info: Starting Tunnel Connection Checker Mar 12 21:17:48 rivoplus volumio[3293]: info: Starting Firmware update Mar 12 21:17:48 rivoplus volumio[3293]: info: Checking new Firmware Integrity Mar 12 21:17:48 rivoplus volumio[3293]: info: New Firmware Integrity Check Passed Mar 12 21:17:48 rivoplus volumio[3293]: info: Writing new MCU Firmware Mar 12 21:17:48 rivoplus volumio[3293]: info: Removing MCU Folder mountpoint Mar 12 21:17:48 rivoplus volumio[3293]: info: MYVolumio Device enabled Mar 12 21:17:48 rivoplus volumio[3293]: info: MyVolumio status changed Mar 12 21:17:48 rivoplus volumio[3293]: info: Streaming services startup Mar 12 21:17:48 rivoplus volumio[3293]: info: Starting Streaming Daemon Mar 12 21:17:48 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 12 21:17:48 rivoplus sudo[4183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 12 21:17:48 rivoplus sudo[4183]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:48 rivoplus sudo[4183]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:48 rivoplus volumio[3293]: info: Setting Geolocation for MyVolumio to eu6 Mar 12 21:17:48 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:48 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:48 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:48 rivoplus volumio[3293]: error: Cannot start Volumio Streaming Daemon Mar 12 21:17:48 rivoplus volumio[3293]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 12 21:17:48 rivoplus volumio[3293]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 12 21:17:48 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 12 21:17:48 rivoplus volumio[3293]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 12 21:17:49 rivoplus volumio[3293]: info: Setting Geolocation for MyVolumio to eu6 Mar 12 21:17:49 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:49 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:49 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Mar 12 21:17:49 rivoplus volumio[3293]: info: Successfully Added MyVolumio device Mar 12 21:17:49 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 12 21:17:49 rivoplus volumio[3293]: info: Successfully Added MyVolumio device Mar 12 21:17:49 rivoplus volumio[3293]: info: Updating MyVolumio device info Mar 12 21:17:49 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:50 rivoplus volumio[3293]: info: Updating MyVolumio device info Mar 12 21:17:50 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:50 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:50 rivoplus volumio[3293]: info: Successfully Updated MyVolumio device Mar 12 21:17:50 rivoplus volumio[3293]: info: Successfully Updated MyVolumio device Mar 12 21:17:53 rivoplus volumio[3293]: error: MyVolumio Plugin failed to start in a timely fashion Mar 12 21:17:53 rivoplus volumio[3293]: [Metrics] CommandRouter: 52s 820.25ms Mar 12 21:17:53 rivoplus volumio[3293]: info: CoreCommandRouter::volumiosetStartupVolume Mar 12 21:17:53 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:17:53 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:53 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:53 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:17:54 rivoplus volumio[3293]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 12 21:17:54 rivoplus volumio[3293]: info: Received Get System Version Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:54 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:54 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:54 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 12 21:17:54 rivoplus volumio[3293]: No protocol specified Mar 12 21:17:54 rivoplus volumio[3293]: xcb_connection_has_error() returned true Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 12 21:17:54 rivoplus volumio[3293]: info: Version has changed, forcing UI Reload Mar 12 21:17:54 rivoplus volumio[3293]: info: CoreCommandRouter::Reload Ui Mar 12 21:17:56 rivoplus sudo[4213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 12 21:17:56 rivoplus sudo[4213]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:56 rivoplus sudo[4217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 12 21:17:56 rivoplus sudo[4217]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:56 rivoplus sudo[4213]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:56 rivoplus sudo[4217]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:56 rivoplus volumio[3293]: verbose: New Socket.io Connection to 192.168.1.118 from 192.168.1.164 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 12 21:17:56 rivoplus volumio[3293]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 7 Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:56 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:56 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:56 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:56 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:56 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:56 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:56 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 12 21:17:56 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Mar 12 21:17:57 rivoplus volumio[3293]: info: Received Get System Version Mar 12 21:17:57 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:57 rivoplus sudo[4230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 12 21:17:57 rivoplus sudo[4230]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:57 rivoplus systemd[1]: Started MyVolumio SSH Tunnel. Mar 12 21:17:57 rivoplus sudo[4230]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:57 rivoplus volumio[3293]: info: Remote SSH Started Mar 12 21:17:57 rivoplus autossh[4240]: port set to 0, monitoring disabled Mar 12 21:17:57 rivoplus autossh[4240]: starting ssh (count 1) Mar 12 21:17:57 rivoplus autossh[4240]: ssh child pid is 4244 Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: USB disconnect, device number 7 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:58 rivoplus volumio[3293]: info: Partition removed: {"syspath":"/sys/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-RPI_RP2_E0C9125B0D9B-0:0-part1 /dev/disk/by-label/RPI-RP2 /dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1.4.1:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/ff500000.dwc3/xhci-hcd.0.auto/usb1/1-1/1-1.4/1-1.4.1/1-1.4.1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"RPI-RP2","ID_FS_LABEL_ENC":"RPI-RP2","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_VERSION":"FAT16","ID_INSTANCE":"0:0","ID_MODEL":"RP2","ID_MODEL_ENC":"RP2\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0003","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"262143","ID_PART_ENTRY_TYPE":"0xe","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1.4.1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_4_1_1_0-scsi-0_0_0_0","ID_REVISION":"3","ID_SERIAL":"RPI_RP2_E0C9125B0D9B-0:0","ID_SERIAL_SHORT":"E0C9125B0D9B","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:ff0000:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"RPI","ID_VENDOR_ENC":"RPI\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"2e8a","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"6166","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"77906247"} Mar 12 21:17:58 rivoplus volumiossh-tunnel[4232]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Mar 12 21:17:58 rivoplus sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Mar 12 21:17:58 rivoplus sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:17:58 rivoplus systemd[1]: media-RPI\x2dRP2.mount: Succeeded. Mar 12 21:17:58 rivoplus kernel: FAT-fs (sda1): unable to read boot sector to mark fs as dirty Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: new full-speed USB device number 8 using xhci-hcd Mar 12 21:17:58 rivoplus sudo[4248]: pam_unix(sudo:session): session closed for user root Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:17:58 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:58 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: New USB device found, idVendor=2e8a, idProduct=0005 Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: Product: Board in FS mode Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: Manufacturer: MicroPython Mar 12 21:17:58 rivoplus kernel: usb 1-1.4.1: SerialNumber: e6623cc21f507626 Mar 12 21:17:58 rivoplus kernel: cdc_acm 1-1.4.1:1.0: ttyACM0: USB ACM device Mar 12 21:17:58 rivoplus volumio-remote-updater[2841]: Test mode enabled Mar 12 21:17:58 rivoplus volumio-remote-updater[2841]: No alpha test mode Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 12 21:17:58 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 12 21:17:58 rivoplus volumio[3293]: error: Serial API: Failed to decode command: Mar 12 21:17:58 rivoplus volumio[3293]: PUSH, message: SLEEP Mar 12 21:17:58 rivoplus volumio[3293]: info: MCU Signalled Bootloader Mode Disabled Mar 12 21:17:58 rivoplus volumio[3293]: info: Reporting MCU Network Status: 2 Mar 12 21:17:58 rivoplus volumio[3293]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 9 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:58 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:58 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:58 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:17:58 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:58 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetVisibleSources Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 12 21:17:58 rivoplus volumio[3293]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 12 21:17:58 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:17:58 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: info: Listing playlists Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 12 21:17:58 rivoplus volumio[3293]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 12 21:17:58 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:58 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:17:58 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:17:59 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 12 21:17:59 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:17:59 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 12 21:17:59 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:17:59 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:18:00 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:18:00 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:00 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 12 21:18:00 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:18:00 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:00 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:00 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:18:00 rivoplus volumio[3293]: info: BOOT COMPLETED Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 12 21:18:01 rivoplus volumio[3293]: info: Not Reporting Auto name since its the default one Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Mar 12 21:18:01 rivoplus volumio[3293]: No protocol specified Mar 12 21:18:01 rivoplus volumio[3293]: xcb_connection_has_error() returned true Mar 12 21:18:01 rivoplus sudo[4258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Mar 12 21:18:01 rivoplus sudo[4258]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:18:01 rivoplus sudo[4258]: pam_unix(sudo:session): session closed for user root Mar 12 21:18:01 rivoplus sudo[4260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Mar 12 21:18:01 rivoplus sudo[4260]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:18:01 rivoplus systemd[1]: Started RAAT DAEMON. Mar 12 21:18:01 rivoplus sudo[4260]: pam_unix(sudo:session): session closed for user root Mar 12 21:18:01 rivoplus volumio[3293]: info: Raat Daemon started successfully Mar 12 21:18:01 rivoplus volumio[3293]: info: Executing endpoint restartRAATSocket Mar 12 21:18:01 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Mar 12 21:18:01 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:01 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 12 21:18:02 rivoplus volumio[3293]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/RPI-RP2 Mar 12 21:18:03 rivoplus volumio[3293]: info: Scanning removed location : "USB/RPI-RP2" Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 13 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 12 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 10 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 9 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 8 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 6 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ------------------------------ 233ms Mar 12 21:18:03 rivoplus volumio[3293]: info: ------------------------------ 231ms Mar 12 21:18:03 rivoplus volumio[3293]: info: ------------------------------ 230ms Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: Mar 12 21:18:03 rivoplus volumio[3293]: ---------------------------- MPD announces state update: update Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::getState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::sendMpdCommand status Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 239 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 237 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 12 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 10 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 9 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: info: sendMpdCommand status took 7 milliseconds Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: Command Router : Notfying DB Updatefalse Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::Close All Modals sent Mar 12 21:18:03 rivoplus volumio[3293]: verbose: ControllerMpd::parseState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ControllerMpd::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 12 21:18:03 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: No code Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:03 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:03 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:03 rivoplus volumio[3293]: info: ------------------------------ 455ms Mar 12 21:18:04 rivoplus volumio[3293]: info: ------------------------------ 228ms Mar 12 21:18:04 rivoplus volumio[3293]: info: ------------------------------ 227ms Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:04 rivoplus volumio[3293]: info: RAAT: Requesting Headphone Status Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Mar 12 21:18:04 rivoplus volumio[3293]: info: MCU Signalled Headphone Mode Disabled Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 12 21:18:04 rivoplus volumio[3293]: info: Reporting Headphone State: false Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: Updating RAAT Signal Path Mar 12 21:18:04 rivoplus volumio[3784]: ...................................................................................................................................................................................................................................................................................................................................................................................................++++ Mar 12 21:18:04 rivoplus volumio[3784]: e is 65537 (0x010001) Mar 12 21:18:04 rivoplus volumio[3784]: writing RSA key Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetBrowseSources Mar 12 21:18:04 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:18:06 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 12 21:18:07 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 12 21:18:08 rivoplus volumio[3293]: info: Reinitializing MCU Serial Connection Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: inputs , initializeSerialPort Mar 12 21:18:08 rivoplus volumio[3293]: info: Serial port is open, closing it Mar 12 21:18:08 rivoplus volumio[3293]: info: Initializing Serial Communication on port /dev/ttyS3 Mar 12 21:18:08 rivoplus volumio[3293]: info: Serial port opened successfully Mar 12 21:18:08 rivoplus volumio[3293]: info: Sending serial start messages Mar 12 21:18:08 rivoplus kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 115200 to 115200 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:08 rivoplus volumio[3293]: info: Reporting MCU Network Status: 2 Mar 12 21:18:08 rivoplus volumio[3293]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:08 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreStateMachine::pushState Mar 12 21:18:08 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::volumioPushState Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.6 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting HW Firmware info: 0.2.6 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting HW Version info: 1.0 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting Additional System Software info: Hardware Revision: 1.0, Firmware Version: 0.2.7 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting HW Firmware info: 0.2.7 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 12 21:18:08 rivoplus volumio[3293]: info: Setting HW Version info: 1.0 Mar 12 21:18:08 rivoplus volumio[3293]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 12 21:18:08 rivoplus volumio[3293]: info: MCU Signalled Headphone Mode Disabled Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 12 21:18:08 rivoplus volumio[3293]: info: Reporting Headphone State: false Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 12 21:18:08 rivoplus volumio[3293]: info: Updating RAAT Signal Path Mar 12 21:18:08 rivoplus volumio[3293]: info: MCU Signalled Sleep Mode Disabled Mar 12 21:18:08 rivoplus volumio[3293]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.slZhUZ0H41Myb5yNKwgaNSuVcry1.a49ba3df5b09886550f6ea6e26ea79da.state.mute' Mar 12 21:18:08 rivoplus sudo[4320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Mar 12 21:18:08 rivoplus sudo[4320]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:18:08 rivoplus volumio[3293]: info: MCU Signalled Auto Boot Mode On Power Disabled Mar 12 21:18:08 rivoplus volumio[3293]: info: Adding Inputs via Serial API Mar 12 21:18:08 rivoplus volumio[3293]: info: Adding Advanced Audio Settings via Serial API Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Mar 12 21:18:08 rivoplus sudo[4320]: pam_unix(sudo:session): session closed for user root Mar 12 21:18:08 rivoplus kernel: fb: osd[0] enable: 1 (Xorg) Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::servicePushState Mar 12 21:18:08 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:08 rivoplus volumio[3293]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Mar 12 21:18:08 rivoplus volumio[3293]: verbose: CURRENT POSITION 0 Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreStateMachine::syncState stateService play Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreStateMachine::syncState currentStatus stop Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 12 21:18:08 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 12 21:18:08 rivoplus volumio[3293]: Cannot find translation for source QOBUZ Mar 12 21:18:08 rivoplus sudo[4323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Mar 12 21:18:08 rivoplus sudo[4323]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 12 21:18:08 rivoplus sudo[4323]: pam_unix(sudo:session): session closed for user root Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:18:14 rivoplus volumio[3293]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 12 21:18:14 rivoplus volumio[3293]: info: Received Get System Version Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:18:14 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:18:14 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:14 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:14 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 12 21:18:15 rivoplus volumio[3293]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 12 21:18:15 rivoplus volumio[3293]: info: Received Get System Version Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 12 21:18:15 rivoplus volumio[3293]: info: Received Get System Info Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 12 21:18:15 rivoplus volumio[3293]: info: Discovery: Getting this device information Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:15 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:15 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 12 21:18:28 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetState Mar 12 21:18:28 rivoplus volumio[3293]: info: CorePlayQueue::getTrack 0 Mar 12 21:18:32 rivoplus volumio[3293]: info: CoreCommandRouter::volumioGetQueue Mar 12 21:18:32 rivoplus volumio[3293]: info: CoreStateMachine::getQueue Mar 12 21:18:32 rivoplus volumio[3293]: info: CorePlayQueue::getQueue Mar 12 21:18:33 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 12 21:18:33 rivoplus volumio[3293]: info: handleBrowseUri took 416 milliseconds Mar 12 21:18:33 rivoplus volumio[3293]: info: Preload queue cleared Mar 12 21:18:33 rivoplus volumio[3293]: info: Preload queue cleared Mar 12 21:18:38 rivoplus volumio[3293]: Searching plugin music_service/qobuz Mar 12 21:18:38 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 12 21:18:39 rivoplus volumio[3293]: Searching plugin music_service/qobuz Mar 12 21:18:39 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Mar 12 21:18:39 rivoplus volumio[3293]: info: searchQOBUZUri took 1607 milliseconds Mar 12 21:18:39 rivoplus volumio[3293]: info: All search sources collected, pushing search results Mar 12 21:18:41 rivoplus volumio[3293]: info: searchQOBUZUri took 1926 milliseconds Mar 12 21:18:41 rivoplus volumio[3293]: info: All search sources collected, pushing search results Mar 12 21:18:53 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 12 21:18:54 rivoplus volumio[3293]: info: handleBrowseUri took 924 milliseconds Mar 12 21:18:54 rivoplus volumio[3293]: info: Preload queue cleared Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979859 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979860 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979861 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979862 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979863 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979864 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979865 Mar 12 21:18:54 rivoplus volumio[3293]: info: Preloading song: qobuz://song/35979866 Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979859 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979860 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:54 rivoplus volumio[3293]: info: Executing endpoint getSimilarAlbums Mar 12 21:18:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979861 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:54 rivoplus volumio[3293]: info: Executing endpoint metavolumio Mar 12 21:18:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979862 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:54 rivoplus volumio[3293]: info: Executing endpoint metavolumio Mar 12 21:18:54 rivoplus volumio[3293]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979863 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:54 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979864 in service qobuz Mar 12 21:18:54 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:55 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979865 in service qobuz Mar 12 21:18:55 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:55 rivoplus volumio[3293]: info: Exploding uri qobuz://song/35979866 in service qobuz Mar 12 21:18:55 rivoplus volumio[3293]: https://prod.vlmapi.io/v2/qobuz/explodeUri Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 610 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 528 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 650 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 574 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 667 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 567 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 618 milliseconds Mar 12 21:18:55 rivoplus volumio[3293]: info: explodeUri took 686 milliseconds Mar 12 21:18:56 rivoplus volumio[3293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 21:18:56 rivoplus volumio[3293]: Error: read ECONNRESET Mar 12 21:18:56 rivoplus volumio[3293]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Mar 12 21:18:56 rivoplus volumio[3293]: errno: -104, Mar 12 21:18:56 rivoplus volumio[3293]: code: 'ECONNRESET', Mar 12 21:18:56 rivoplus volumio[3293]: syscall: 'read' Mar 12 21:18:56 rivoplus volumio[3293]: } Mar 12 21:18:56 rivoplus volumio[3293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 12 21:18:57 rivoplus sudo[4472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-12 21:17 Mar 12 21:18:57 rivoplus sudo[4472]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:29:52 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="c26baf739f7d1f3fe648e6cf51b2c348"