-- Logs begin at Sat 2025-04-26 11:20:54 CEST, end at Sat 2025-04-26 11:28:45 CEST. -- Apr 26 11:28:02 vgeithan systemd-timedated[3451]: Changed local time to Sat Apr 26 11:28:02 2025 Apr 26 11:28:02 vgeithan sudo[3449]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:02 vgeithan volumio-time-update[2849]: volumio-time-update-util: System time updated successfully. Apr 26 11:28:02 vgeithan systemd[1]: Started Volumio Time Update Utility. Apr 26 11:28:02 vgeithan systemd[1]: Reached target Multi-User System. Apr 26 11:28:02 vgeithan systemd[1]: Reached target Graphical Interface. Apr 26 11:28:02 vgeithan systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 26 11:28:02 vgeithan systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 26 11:28:02 vgeithan systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 26 11:28:02 vgeithan systemd[1]: Startup finished in 12.906s (kernel) + 30.200s (userspace) = 43.106s. Apr 26 11:28:02 vgeithan ntpd[3319]: Soliciting pool server 185.153.41.4 Apr 26 11:28:02 vgeithan ntpd[3319]: Soliciting pool server 162.159.200.1 Apr 26 11:28:02 vgeithan ntpd[3319]: Soliciting pool server 45.87.77.15 Apr 26 11:28:03 vgeithan ntpd[3319]: Soliciting pool server 91.177.129.212 Apr 26 11:28:03 vgeithan ntpd[3319]: Soliciting pool server 44.31.68.23 Apr 26 11:28:03 vgeithan volumio[3317]: info: Loading plugin "network"... Apr 26 11:28:03 vgeithan volumio[3317]: info: Refreshing Cached IP Addresses Apr 26 11:28:03 vgeithan sudo[3457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 11:28:03 vgeithan sudo[3457]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:03 vgeithan sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 11:28:03 vgeithan sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:03 vgeithan sudo[3457]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:03 vgeithan volumio[3317]: info: Loading plugin "services"... Apr 26 11:28:03 vgeithan sudo[3459]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:03 vgeithan volumio[3317]: info: Loading plugin "alsa_controller"... Apr 26 11:28:03 vgeithan sudo[3471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 11:28:03 vgeithan sudo[3471]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:03 vgeithan kernel: wl_escan_set_scan: ioctl_ver=2 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:03 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 11:28:03 vgeithan volumio[3317]: info: Loading plugin "wizard"... Apr 26 11:28:03 vgeithan volumio[3317]: info: Loading plugin "networkfs"... Apr 26 11:28:03 vgeithan volumio[3317]: info: Starting Udev Watcher for removable devices Apr 26 11:28:04 vgeithan volumio[3317]: info: Ignoring mount for partition: BOOT Apr 26 11:28:04 vgeithan volumio[3317]: info: Ignoring mount for partition: volumio Apr 26 11:28:04 vgeithan volumio[3317]: info: Ignoring mount for partition: volumio_data Apr 26 11:28:04 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 11:28:04 vgeithan volumio[3317]: info: Loading plugin "volumio_command_line_client"... Apr 26 11:28:04 vgeithan volumio[3317]: info: Plugin upnp is not enabled Apr 26 11:28:04 vgeithan volumio[3317]: info: Loading plugin "my_music"... Apr 26 11:28:04 vgeithan volumio[3317]: info: Loading plugin "mpd"... Apr 26 11:28:04 vgeithan ntpd[3319]: Soliciting pool server 45.87.76.3 Apr 26 11:28:04 vgeithan ntpd[3319]: Soliciting pool server 2001:470:1f15:1377::1 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan volumio[3317]: info: Plugin upnp_browser is not enabled Apr 26 11:28:04 vgeithan volumio[3317]: info: Plugin bluetooth is not enabled Apr 26 11:28:04 vgeithan volumio[3317]: info: Loading plugin "alarm-clock"... Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan volumio[3317]: info: Loading plugin "manifestui"... Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:04 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan volumio[3317]: info: Plugin metavolumio is not enabled Apr 26 11:28:05 vgeithan volumio[3317]: info: Plugin airplay_emulation is not enabled Apr 26 11:28:05 vgeithan volumio[3317]: info: Plugin cd_controller is not enabled Apr 26 11:28:05 vgeithan volumio[3317]: info: Loading plugin "last_100"... Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan volumio[3317]: info: Plugin raat is not enabled Apr 26 11:28:05 vgeithan volumio[3317]: info: Loading plugin "streaming_services"... Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan systemd[1]: systemd-fsckd.service: Succeeded. Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:05 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:06 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:07 vgeithan volumio[3317]: info: Starting Streaming Service Transparent Proxy Apr 26 11:28:07 vgeithan volumio[3317]: info: Plugin tidalconnect is not enabled Apr 26 11:28:07 vgeithan volumio[3317]: info: Loading plugin "webradio"... Apr 26 11:28:07 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:07 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 8 Apr 26 11:28:07 vgeithan volumio[3317]: info: Loading plugin "i2s_dacs"... Apr 26 11:28:07 vgeithan volumio[3317]: info: I2S DAC not set, start Auto-detection Apr 26 11:28:07 vgeithan volumio[3317]: info: Loading plugin "volumiodiscovery"... Apr 26 11:28:07 vgeithan sh[2755]: timed out Apr 26 11:28:07 vgeithan sh[2755]: dhcpcd exited Apr 26 11:28:07 vgeithan dhcpcd[2808]: timed out Apr 26 11:28:07 vgeithan dhcpcd[2808]: dhcpcd exited Apr 26 11:28:07 vgeithan sh[2755]: ifup: failed to bring up eth0 Apr 26 11:28:07 vgeithan systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Apr 26 11:28:07 vgeithan systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** For more information see Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 11:28:07 vgeithan volumio[3317]: *** WARNING *** For more information see Apr 26 11:28:07 vgeithan volumio[3317]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 11:28:07 vgeithan volumio[3317]: info: Discovery: Started advertising with name: VGeithan Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** For more information see Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 11:28:07 vgeithan node[3317]: *** WARNING *** For more information see Apr 26 11:28:07 vgeithan kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f663000 drv_status 0x0 e_type 69 e_states 0 Apr 26 11:28:07 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 11:28:07 vgeithan volumio[3317]: info: Loading plugin "multiroom"... Apr 26 11:28:07 vgeithan sudo[3471]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.19119f47 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xebb725a9.01c23702 Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.1932c4a8 does not match aorg 0000000000.00000000 from server@109.68.160.220 xmt 0xebb725a9.0241cc3e Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.1924ecc2 does not match aorg 0000000000.00000000 from server@185.111.204.220 xmt 0xebb725a9.02443a8f Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.19098847 does not match aorg 0000000000.00000000 from server@91.177.129.212 xmt 0xebb725a9.02957f3b Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.191b06e4 does not match aorg 0000000000.00000000 from server@185.89.20.5 xmt 0xebb725a9.03d88502 Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.19284ffe does not match aorg 0000000000.00000000 from server@81.82.227.219 xmt 0xebb725a9.03f3c47e Apr 26 11:28:09 vgeithan ntpd[3319]: receive: Unexpected origin timestamp 0xebb725a8.19379062 does not match aorg 0000000000.00000000 from server@213.118.218.134 xmt 0xebb725a9.03e4ae77 Apr 26 11:28:09 vgeithan volumio[3317]: STREAMING PROXY: Starting server on port 3245 Apr 26 11:28:09 vgeithan volumio[3317]: Node JS runtime: 14 Apr 26 11:28:10 vgeithan volumio[3317]: info: Applying required configuration parameters for plugin multiroom Apr 26 11:28:10 vgeithan sudo[3594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 26 11:28:10 vgeithan sudo[3594]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:10 vgeithan sudo[3594]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:11 vgeithan volumio[3317]: info: MRS: MultiRoom plugin initialized Apr 26 11:28:11 vgeithan volumio[3317]: info: MRS: STOPPING SNAPCLIENT Apr 26 11:28:11 vgeithan volumio[3317]: info: MRS: Snap server stop Apr 26 11:28:11 vgeithan volumio[3317]: info: MRS: STOPPING volumioStreaming Apr 26 11:28:11 vgeithan sudo[3610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 26 11:28:11 vgeithan sudo[3610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:11 vgeithan sudo[3612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 26 11:28:11 vgeithan sudo[3612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:11 vgeithan volumio[3317]: info: Loading plugin "outputs"... Apr 26 11:28:11 vgeithan sudo[3610]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:11 vgeithan volumio[3317]: info: Loading plugin "albumart"... Apr 26 11:28:11 vgeithan sudo[3619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 26 11:28:11 vgeithan sudo[3619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:11 vgeithan sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 26 11:28:11 vgeithan sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:11 vgeithan sudo[3612]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:11 vgeithan volumio[3317]: info: Plugin example_plugin is not enabled Apr 26 11:28:11 vgeithan volumio[3317]: info: Loading plugin "hi_res_audio"... Apr 26 11:28:11 vgeithan sudo[3620]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:11 vgeithan sudo[3619]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:12 vgeithan volumio[3317]: Forking 3 albumart workers Apr 26 11:28:14 vgeithan volumio[3317]: Starting albumart workers Apr 26 11:28:14 vgeithan volumio[3317]: Starting albumart workers Apr 26 11:28:14 vgeithan volumio[3317]: Starting albumart workers Apr 26 11:28:15 vgeithan volumio[3317]: info: Applying required configuration parameters for plugin hi_res_audio Apr 26 11:28:15 vgeithan volumio[3317]: info: Loading plugin "inputs"... Apr 26 11:28:15 vgeithan volumio-remote-updater[2832]: [2025-04-26 11:28:15] [connect] Successful connection Apr 26 11:28:16 vgeithan volumio[3317]: info: Loading plugin "qobuz"... Apr 26 11:28:18 vgeithan volumio[3317]: info: Plugin smart_inputs is not enabled Apr 26 11:28:18 vgeithan volumio[3317]: info: Loading plugin "tidal"... Apr 26 11:28:18 vgeithan systemd[1]: systemd-hostnamed.service: Succeeded. Apr 26 11:28:20 vgeithan volumio[3317]: info: Loading plugin "rivocontrol"... Apr 26 11:28:20 vgeithan volumio[3317]: info: Adding this device properties Apr 26 11:28:20 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 26 11:28:20 vgeithan volumio[3317]: info: Setting Additional Device Volumio Properties: [object Object] Apr 26 11:28:20 vgeithan volumio[3317]: info: Loading plugin "updater_comm"... Apr 26 11:28:21 vgeithan volumio[3317]: info: Loading plugin "cec_controller"... Apr 26 11:28:21 vgeithan volumio[3317]: info: Applying required configuration parameters for plugin cec_controller Apr 26 11:28:21 vgeithan volumio[3317]: info: Plugin mpdemulation is not enabled Apr 26 11:28:21 vgeithan volumio[3317]: info: Loading plugin "rest_api"... Apr 26 11:28:21 vgeithan volumio[3317]: info: Loading plugin "websocket"... Apr 26 11:28:21 vgeithan volumio[3317]: info: Starting Socket.io Server version 2.3.0 Apr 26 11:28:21 vgeithan volumio[3317]: info: Loading i18n strings for locale en Apr 26 11:28:21 vgeithan volumio[3317]: Updating browse sources language Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::initPlayerControls Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 11:28:21 vgeithan volumio[3317]: Express server listening on port 3000 Apr 26 11:28:21 vgeithan volumio[3317]: [Metrics] WebUI: 21s 871.25ms Apr 26 11:28:21 vgeithan volumio[3317]: info: CoreStateMachine::resetVolumioState Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::getcurrentVolume Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:22 vgeithan volumio[3317]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 26 11:28:22 vgeithan volumio[3317]: info: Completed loading Core Plugins Apr 26 11:28:22 vgeithan volumio[3317]: info: Preparing to generate the ALSA configuration file Apr 26 11:28:22 vgeithan volumio[3317]: info: Volumio Network Manager: Network status updated: 2 Apr 26 11:28:22 vgeithan volumio[3317]: info: MRS: Removed streaming files Apr 26 11:28:22 vgeithan volumio[3317]: info: MRS: volumioStreaming STOPPED Apr 26 11:28:22 vgeithan volumio[3317]: info: MRS: SNAPSERVER STOPPED Apr 26 11:28:22 vgeithan volumio[3317]: info: MRS: SNAPCLIENT STOPPED Apr 26 11:28:22 vgeithan volumio[3317]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 26 11:28:22 vgeithan volumio[3317]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 26 11:28:22 vgeithan volumio[3317]: info: Reading ALSA contributions from plugins. Apr 26 11:28:22 vgeithan volumio[3317]: verbose: New Socket.io Connection to 192.168.0.227:3000 from 192.168.0.223 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 26 11:28:22 vgeithan volumio[3317]: verbose: New Socket.io Connection to 192.168.0.227:3000 from 192.168.0.223 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 26 11:28:22 vgeithan kernel: fb: mem_free_work, free memory: addr:800000 Apr 26 11:28:22 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:22 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:22 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:22 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:22 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:22 vgeithan volumio[3317]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 26 11:28:22 vgeithan volumio[3317]: info: Reloading queue from file Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::setRepeat false single undefined Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:22 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::setRandom null Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:22 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:22 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:22 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:22 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:22 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:22 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:22 vgeithan volumio[3317]: info: VolumeController:: Volume=undefined Mute =false Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:22 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreStateMachine::updateTrackBlock Apr 26 11:28:22 vgeithan volumio[3317]: info: CorePlayQueue::getTrackBlock Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 11:28:22 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:22 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:22 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:22 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:22 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:22 vgeithan volumio[3317]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 26 11:28:22 vgeithan volumio[3317]: verbose: New Socket.io Connection to 192.168.0.227:3000 from 192.168.0.223 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 26 11:28:22 vgeithan volumio[3317]: info: Asound.conf file unchanged, so no further update is needed Apr 26 11:28:22 vgeithan volumio[3317]: info: Output device has changed, restarting MPD Apr 26 11:28:22 vgeithan volumio[3317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:22 vgeithan sudo[3793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 11:28:22 vgeithan sudo[3793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:22 vgeithan volumio[3317]: info: ___________ START PLUGINS ___________ Apr 26 11:28:22 vgeithan sudo[3793]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:22 vgeithan sudo[3795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 11:28:22 vgeithan sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:22 vgeithan volumio[3317]: info: ControllerMpd::onStart: Initializing MPD Apr 26 11:28:22 vgeithan volumio[3317]: info: Creating MPD Configuration file Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 11:28:22 vgeithan volumio[3317]: info: [1745659702880] CoreMusicLibrary::Adding element Last_100 Apr 26 11:28:22 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:22 vgeithan volumio[3317]: info: Streaming services startup Apr 26 11:28:22 vgeithan volumio[3317]: info: Starting Streaming Daemon Apr 26 11:28:22 vgeithan systemd[1]: Stopping Music Player Daemon... Apr 26 11:28:22 vgeithan sudo[3801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 11:28:22 vgeithan sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:22 vgeithan sudo[3803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 11:28:22 vgeithan sudo[3803]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:22 vgeithan sudo[3801]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:22 vgeithan sudo[3807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 11:28:22 vgeithan sudo[3807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3807]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan systemd[1]: mpd.service: Succeeded. Apr 26 11:28:23 vgeithan systemd[1]: Stopped Music Player Daemon. Apr 26 11:28:23 vgeithan systemd[1]: Starting Music Player Daemon... Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 11:28:23 vgeithan volumio[3317]: info: [1745659703068] CoreMusicLibrary::Adding element Webradio Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 11:28:23 vgeithan volumio[3317]: info: Initializing BBC Radios Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 26 11:28:23 vgeithan volumio[3317]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 26 11:28:23 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:23 vgeithan sudo[3815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 11:28:23 vgeithan sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan volumio[3317]: error: Hi Res Audio Failed Login: Missing Login Data Apr 26 11:28:23 vgeithan volumio[3317]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 26 11:28:23 vgeithan volumio[3317]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 26 11:28:23 vgeithan volumio[3317]: info: Initializing Serial Communication on port /dev/ttyS3 Apr 26 11:28:23 vgeithan sudo[3815]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan volumio[3317]: info: Touch Event Listener Process Starting Apr 26 11:28:23 vgeithan kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Apr 26 11:28:23 vgeithan kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Apr 26 11:28:23 vgeithan kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Apr 26 11:28:23 vgeithan volumio[3317]: info: Refreshing QOBUZ token Apr 26 11:28:23 vgeithan sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Apr 26 11:28:23 vgeithan sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Apr 26 11:28:23 vgeithan sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3848]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan sudo[3851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Apr 26 11:28:23 vgeithan sudo[3851]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3851]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan sudo[3854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Apr 26 11:28:23 vgeithan sudo[3854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3854]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Apr 26 11:28:23 vgeithan sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3857]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan volumio[3317]: info: Apply VIM3L Onboard LEDs Settings Apr 26 11:28:23 vgeithan volumio[3317]: info: Turning On Onboard LEDs Apr 26 11:28:23 vgeithan sudo[3861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 26 11:28:23 vgeithan sudo[3861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan volumio[3317]: info: Starting CEC Event listener Apr 26 11:28:23 vgeithan volumio[3317]: info: Volumio Calling Home Apr 26 11:28:23 vgeithan sudo[3861]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 26 11:28:23 vgeithan sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:23 vgeithan sudo[3865]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:23 vgeithan volumio[3317]: info: Stopping AccessToken refresher cron Apr 26 11:28:23 vgeithan volumio[3317]: info: AccessToken refresher cron started Apr 26 11:28:23 vgeithan volumio[3317]: info: Adding TIDAL REST API Endpoints Apr 26 11:28:24 vgeithan volumio[3317]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: adding ab1cdfe8-452a-45f3-8e9b-9032bd8de273 Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: Found device VGeithan Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:24 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output for this device Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:24 vgeithan volumio[3317]: info: Adding audio output: Apr 26 11:28:24 vgeithan volumio[3317]: info: Adding audio output: Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: adding 50b7befd-0459-42cc-a439-22721c560b3c Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: Found device VTannoy Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: Connecting to remote: 192.168.0.243 Apr 26 11:28:24 vgeithan volumio[3317]: info: Serial port opened successfully Apr 26 11:28:24 vgeithan volumio[3317]: info: Sending serial start messages Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: Reporting MCU Network Status: 2 Apr 26 11:28:24 vgeithan volumio[3317]: info: MP1 GPIO: Signalled system ready via GPIO Apr 26 11:28:24 vgeithan volumio[3317]: error: Cannot start Volumio Streaming Daemon Apr 26 11:28:24 vgeithan volumio[3317]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 11:28:24 vgeithan volumio[3317]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 11:28:24 vgeithan volumio[3317]: info: MPD Permissions set Apr 26 11:28:24 vgeithan volumio[3317]: info: MPD Permissions set Apr 26 11:28:24 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:24 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:24 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:24 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:24 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:24 vgeithan volumio[3317]: info: VolumeController:: Volume=undefined Mute =false Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:24 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting Device Volume Override Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 26 11:28:24 vgeithan volumio[3317]: info: Updating Volume Controller Parameters: Device: 5 Name: Audioengine D1 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 26 11:28:24 vgeithan volumio[3317]: info: Disabling external Volume Control Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:24 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting Additional System Software info: Hardware Revision: 2.0 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting HW Firmware info: undefined Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting HW Version info: 2.0 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.4 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting HW Firmware info: 0.3.4 Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 26 11:28:24 vgeithan volumio[3317]: info: Setting HW Version info: 2.0 Apr 26 11:28:24 vgeithan volumio[3317]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 26 11:28:24 vgeithan volumio[3317]: info: MCU Signalled Headphone Mode Disabled Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 26 11:28:24 vgeithan volumio[3317]: info: MCU Signalled Sleep Mode Disabled Apr 26 11:28:24 vgeithan volumio[3317]: info: Enabling Advanced system settings configuration Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 26 11:28:24 vgeithan volumio[3317]: info: Additional UI Settings Added for plugin music_service/inputs Apr 26 11:28:24 vgeithan volumio[3317]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 26 11:28:24 vgeithan sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 26 11:28:24 vgeithan sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing audio output on request Apr 26 11:28:24 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:24 vgeithan volumio[3317]: info: Discovery: Connected to remote: 192.168.0.243 Apr 26 11:28:24 vgeithan sudo[3878]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:24 vgeithan kernel: fb: osd[0] enable: 1 (Xorg) Apr 26 11:28:24 vgeithan volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 26 11:28:24 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:24 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:24 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:24 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:24 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:24 vgeithan volumio[3317]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:24 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 11:28:25 vgeithan volumio[3317]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 26 11:28:25 vgeithan volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:25 vgeithan sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Apr 26 11:28:25 vgeithan sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:25 vgeithan sudo[3921]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:25 vgeithan volumio[3317]: info: Access Token successfully retrieved Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 11:28:25 vgeithan volumio[3317]: info: [1745659705434] CoreMusicLibrary::Adding element QOBUZ Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:25 vgeithan volumio[3317]: Cannot find translation for source QOBUZ Apr 26 11:28:25 vgeithan volumio[3317]: info: Stopping AccessToken refresher cron for QOBUZ Apr 26 11:28:25 vgeithan volumio[3317]: info: AccessToken refresher cron started for QOBUZ Apr 26 11:28:25 vgeithan volumio[3317]: info: Adding QOBUZ REST API Endpoints Apr 26 11:28:25 vgeithan volumio[3317]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Adding multiroomSync output Apr 26 11:28:25 vgeithan volumio[3317]: info: Adding audio output: Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:25 vgeithan volumio[3317]: Unable to parse: Apr 26 11:28:25 vgeithan volumio[3317]: Simple mixer control 'PCM',0 Apr 26 11:28:25 vgeithan volumio[3317]: Capabilities: pswitch pswitch-joined Apr 26 11:28:25 vgeithan volumio[3317]: Playback channels: Mono Apr 26 11:28:25 vgeithan volumio[3317]: Mono: Playback [on] Apr 26 11:28:25 vgeithan volumio[3317]: info: VolumeController:: Volume=undefined Mute =false Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:25 vgeithan volumio[3317]: info: Adding Inputs via Serial API Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:25 vgeithan volumio[3317]: info: MRS: Found cast device: LS50-Wireless-II-29a2080bb8bdd83b1c492e26c1dcebe7 Apr 26 11:28:25 vgeithan volumio[3317]: info: Adding audio output: Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::servicePushState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:25 vgeithan volumio[3317]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received inputs Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:25 vgeithan volumio[3317]: Cannot find translation for source QOBUZ Apr 26 11:28:25 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:25 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:26 vgeithan volumio[3317]: info: MCU Signalled Playback Inactive Apr 26 11:28:26 vgeithan mpd[3839]: Apr 26 11:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 11:28:26 vgeithan systemd[1]: Started Music Player Daemon. Apr 26 11:28:26 vgeithan sudo[3803]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:26 vgeithan sudo[3795]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:26 vgeithan volumio[3317]: info: Completed starting Core Plugins Apr 26 11:28:26 vgeithan volumio[3317]: info: ------------------------------------------- Apr 26 11:28:26 vgeithan volumio[3317]: info: ----- MyVolumio plugins startup ---- Apr 26 11:28:26 vgeithan volumio[3317]: info: ------------------------------------------- Apr 26 11:28:26 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 26 11:28:26 vgeithan volumio[3317]: error: MPD error: The expression evaluated to a falsy value: Apr 26 11:28:26 vgeithan volumio[3317]: assert.ok(self.idling) Apr 26 11:28:26 vgeithan volumio[3317]: error: The expression evaluated to a falsy value: Apr 26 11:28:26 vgeithan volumio[3317]: assert.ok(self.idling) Apr 26 11:28:26 vgeithan volumio[3317]: info: Turning Off Onboard LEDs Apr 26 11:28:26 vgeithan volumio[3317]: error: updateQueue error: null Apr 26 11:28:26 vgeithan volumio[3317]: info: MPD running with PID3839 Apr 26 11:28:26 vgeithan volumio[3317]: ,establishing connection Apr 26 11:28:26 vgeithan volumio[3317]: error: updateQueue error: null Apr 26 11:28:26 vgeithan sudo[3930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 26 11:28:26 vgeithan sudo[3930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:26 vgeithan sudo[3930]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:26 vgeithan sudo[3933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 26 11:28:26 vgeithan sudo[3933]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:26 vgeithan sudo[3933]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:26 vgeithan volumio[3317]: info: Onboard LEDs initialized Apr 26 11:28:26 vgeithan volumio[3317]: 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: 6 Apr 26 11:28:27 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 11:28:28 vgeithan volumio[3317]: info: MRS: Getting audio outputs on start Apr 26 11:28:28 vgeithan volumio[3317]: info: MRS: Requesting all other devices output Apr 26 11:28:28 vgeithan volumio[3317]: info: Volumio called home Apr 26 11:28:29 vgeithan volumio[3317]: 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 Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:30 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 11:28:30 vgeithan volumio[3317]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 26 11:28:30 vgeithan volumio[3317]: info: Received Get System Info Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:28:30 vgeithan volumio[3317]: info: Discovery: Getting this device information Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:30 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:30 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:30 vgeithan volumio[3317]: info: Listing playlists Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 11:28:30 vgeithan volumio-remote-updater[2832]: [2025-04-26 11:28:30] [connect] Successful connection Apr 26 11:28:30 vgeithan volumio-remote-updater[2832]: [2025-04-26 11:28:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745659710 101 Apr 26 11:28:30 vgeithan volumio[3317]: 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: 8 Apr 26 11:28:30 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 11:28:31 vgeithan volumio[3317]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 26 11:28:31 vgeithan volumio[3317]: info: Received Get System Info Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:28:31 vgeithan volumio[3317]: info: Discovery: Getting this device information Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:31 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:31 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:28:31 vgeithan volumio[3317]: info: Discovery: Getting this device information Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:31 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:28:31 vgeithan volumio[3317]: verbose: New Socket.io Connection to 192.168.0.227:3000 from 192.168.0.119 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:28:31 vgeithan volumio[3317]: info: Discovery: Getting this device information Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:31 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:31 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 26 11:28:31 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 26 11:28:32 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 26 11:28:32 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 26 11:28:32 vgeithan systemd[1]: systemd-timedated.service: Succeeded. Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 11:28:33 vgeithan volumio[3317]: info: Received Get System Info Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 11:28:33 vgeithan volumio[3317]: info: Discovery: Getting this device information Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:33 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 11:28:33 vgeithan volumio[3317]: info: Checking for updated MCU Firmware Apr 26 11:28:33 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 11:28:33 vgeithan volumio[3317]: info: Firware on device is on latest version, no need to update Apr 26 11:28:34 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 11:28:34 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 11:28:36 vgeithan volumio[3317]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 11:28:36 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:36 vgeithan volumio[3317]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 11:28:36 vgeithan volumio[3317]: info: MyVolumio login type: Token Apr 26 11:28:36 vgeithan volumio[3317]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 11:28:36 vgeithan volumio[3317]: 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' Apr 26 11:28:36 vgeithan volumio[3317]: info: MRS: Setting this device as multiroomSync client Apr 26 11:28:36 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:36 vgeithan volumio[3317]: info: MRS: Updating multiroomSync output Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreCommandRouter::volumioStop Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreStateMachine::stop Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:28:36 vgeithan volumio[3317]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 26 11:28:36 vgeithan volumio[3317]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 26 11:28:37 vgeithan volumio[3317]: info: MyVolumio token set successfully Apr 26 11:28:37 vgeithan volumio[3317]: info: MYVOLUMIO: Adding device Apr 26 11:28:37 vgeithan volumio[3317]: info: MYVOLUMIO: Evaluating Server Apr 26 11:28:38 vgeithan volumio[3317]: info: MyVolumio Plan changed: superstar Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 26 11:28:38 vgeithan volumio[3317]: info: Adding audio output: Apr 26 11:28:38 vgeithan volumio[3317]: info: MYVOLUMIO: Adding device Apr 26 11:28:38 vgeithan volumio[3317]: info: MYVOLUMIO: Evaluating Server Apr 26 11:28:38 vgeithan volumio[3317]: info: Remote config written successfully Apr 26 11:28:38 vgeithan volumio[3317]: info: Starting Tunnel 1 Apr 26 11:28:38 vgeithan volumio[3317]: info: Starting Tunnel Connection Checker Apr 26 11:28:38 vgeithan volumio[3317]: info: MRS: Setting this device as client Apr 26 11:28:38 vgeithan volumio[3317]: info: Apr 26 11:28:38 vgeithan volumio[3317]: [1745659718591] ---------------------------- MRS: Setting Multiroom Client Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::volumioStop Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreStateMachine::stop Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreStateMachine::serviceStop Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::serviceStop Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::servicePushState Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:38 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:38 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:38 vgeithan volumio[3317]: info: CorePlayQueue::getTrack 0 Apr 26 11:28:38 vgeithan volumio[3317]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received multiroom Apr 26 11:28:38 vgeithan volumio[3317]: info: MRS: enable multiroom client output Apr 26 11:28:38 vgeithan volumio[3317]: info: MRS: Set multiroom target PCM to volumioDiscard Apr 26 11:28:38 vgeithan volumio[3317]: info: MYVolumio Device enabled Apr 26 11:28:38 vgeithan volumio[3317]: info: MyVolumio status changed Apr 26 11:28:38 vgeithan volumio[3317]: info: Streaming services startup Apr 26 11:28:38 vgeithan volumio[3317]: info: Starting Streaming Daemon Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 26 11:28:38 vgeithan sudo[4036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 11:28:38 vgeithan sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:38 vgeithan volumio[3317]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Apr 26 11:28:38 vgeithan sudo[4036]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:38 vgeithan volumio[3317]: info: Changed audio target for /tmp/multiroom/server/switch.target to volumioDiscard Apr 26 11:28:38 vgeithan volumio[3317]: error: Cannot start Volumio Streaming Daemon Apr 26 11:28:38 vgeithan volumio[3317]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 11:28:38 vgeithan volumio[3317]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin audio_interface/bluetooth is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin audio_interface/multiroom is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin miscellanea/metavolumio is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin miscellanea/manifestui is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/cd_controller is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/smart_inputs is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/hi_res_audio is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/tidal is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/qobuz is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: [MyVolumio PluginManager] Plugin music_service/tidalconnect is enabled for this plan, but could not be found on the local filesystem! Apr 26 11:28:38 vgeithan volumio[3317]: info: Setting Geolocation for MyVolumio to eu8 Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:38 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: Setting Geolocation for MyVolumio to eu5 Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: Successfully Added MyVolumio device Apr 26 11:28:39 vgeithan volumio[3317]: info: MRS: Set multiroom target PCM to volumioLocalPlayback Apr 26 11:28:39 vgeithan volumio[3317]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioLocalPlayback Apr 26 11:28:39 vgeithan volumio[3317]: info: MRS: STARTING SNAPCLIENT Apr 26 11:28:39 vgeithan volumio[3317]: info: MRS: STOPPING SNAPCLIENT Apr 26 11:28:39 vgeithan sudo[4040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumioSnapclient Apr 26 11:28:39 vgeithan sudo[4040]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:39 vgeithan systemd[1]: Starting Snapcast client... Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 11:28:39 vgeithan systemd[1]: Started Snapcast client. Apr 26 11:28:39 vgeithan snapclient[4051]: daemon started Apr 26 11:28:39 vgeithan sudo[4040]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:39 vgeithan volumio[3317]: info: MRS: SNAPCLIENT STARTED Apr 26 11:28:39 vgeithan volumio[3317]: info: Successfully Added MyVolumio device Apr 26 11:28:39 vgeithan snapclient[4051]: Connected to 192.168.0.223 Apr 26 11:28:39 vgeithan volumio[3317]: info: Updating MyVolumio device info Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:39 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:40 vgeithan kernel: usb 1-1.4.2: current rate 8436480 is different from the runtime rate 48000 Apr 26 11:28:40 vgeithan kernel: usb 1-1.4.2: current rate 8436480 is different from the runtime rate 48000 Apr 26 11:28:40 vgeithan volumio[3317]: info: Updating MyVolumio device info Apr 26 11:28:40 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:40 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:40 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 11:28:40 vgeithan volumio[3317]: info: Successfully Updated MyVolumio device Apr 26 11:28:40 vgeithan volumio[3317]: info: Successfully Updated MyVolumio device Apr 26 11:28:43 vgeithan sudo[4067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 26 11:28:43 vgeithan sudo[4067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 11:28:43 vgeithan systemd[1]: Started MyVolumio SSH Tunnel. Apr 26 11:28:43 vgeithan sudo[4067]: pam_unix(sudo:session): session closed for user root Apr 26 11:28:43 vgeithan volumio[3317]: info: Remote SSH Started Apr 26 11:28:43 vgeithan autossh[4070]: port set to 0, monitoring disabled Apr 26 11:28:43 vgeithan autossh[4070]: starting ssh (count 1) Apr 26 11:28:43 vgeithan autossh[4070]: ssh child pid is 4074 Apr 26 11:28:43 vgeithan volumiossh-tunnel[4069]: Warning: Permanently added '[eu5.myvolumio.org]:2222,[185.14.184.111]:2222' (RSA) to the list of known hosts. Apr 26 11:28:43 vgeithan volumio[3317]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 26 11:28:43 vgeithan volumio[3317]: info: CoreCommandRouter::volumioGetState Apr 26 11:28:43 vgeithan volumio[3317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.urZQsO1CKqNpMwOTqdySmzPZfhC2.c9e56fde3b54e8d696d99bc1a7aa6657.state.mute' Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Removing this device as multiroomSync client Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:45 vgeithan volumio[3317]: info: CoreCommandRouter::servicePushState Apr 26 11:28:45 vgeithan volumio[3317]: info: CoreStateMachine::pushState Apr 26 11:28:45 vgeithan volumio[3317]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 11:28:45 vgeithan volumio[3317]: info: CoreCommandRouter::volumioPushState Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output update for this device Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Pushing multiroomSync output Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Setting this device as single Apr 26 11:28:45 vgeithan volumio[3317]: info: Apr 26 11:28:45 vgeithan volumio[3317]: [1745659725099] ---------------------------- Setting Multiroom Single Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: disable multiroom output Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Set multiroom target PCM to volumioDiscard Apr 26 11:28:45 vgeithan volumio[3317]: info: MRS: Updating multiroomSync output Apr 26 11:28:45 vgeithan volumio[3317]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.urZQsO1CKqNpMwOTqdySmzPZfhC2.c9e56fde3b54e8d696d99bc1a7aa6657.state.mute' Apr 26 11:28:45 vgeithan volumio[3317]: info: Changed audio target for /tmp/multiroom/client/switch.target to volumioDiscard Apr 26 11:28:45 vgeithan volumio[3317]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 11:28:45 vgeithan volumio[3317]: Error: EPIPE: broken pipe, write Apr 26 11:28:45 vgeithan volumio[3317]: at Object.writeSync (fs.js:737:3) Apr 26 11:28:45 vgeithan volumio[3317]: at Object.writeFileSync (fs.js:1535:26) Apr 26 11:28:45 vgeithan volumio[3317]: at /volumio/app/plugins/audio_interface/multiroom/multiroom_real:1:32544 Apr 26 11:28:45 vgeithan volumio[3317]: at /volumio/app/plugins/audio_interface/multiroom/node_modules/graceful-fs/graceful-fs.js:362:16 Apr 26 11:28:45 vgeithan volumio[3317]: at FSReqCallback.oncomplete (fs.js:180:23) { Apr 26 11:28:45 vgeithan volumio[3317]: errno: -32, Apr 26 11:28:45 vgeithan volumio[3317]: syscall: 'write', Apr 26 11:28:45 vgeithan volumio[3317]: code: 'EPIPE' Apr 26 11:28:45 vgeithan volumio[3317]: } Apr 26 11:28:45 vgeithan volumio[3317]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 11:28:45 vgeithan sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 11:27 Apr 26 11:28:45 vgeithan sudo[4102]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 08:01:39 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="71b19b3a3eb3d257ef93eee35eba5cf1"