-- Logs begin at Mon 2025-07-14 21:25:24 NZST, end at Sat 2025-07-19 21:00:17 NZST. -- Jul 19 20:59:00 rivo systemd[1]: Started Samba SMB Daemon. Jul 19 20:59:00 rivo systemd[1]: Reached target Multi-User System. Jul 19 20:59:00 rivo systemd[1]: Reached target Graphical Interface. Jul 19 20:59:00 rivo smbd[3405]: [2025/07/19 20:59:00.114639, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 19 20:59:00 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 19 20:59:00 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:00 rivo volumio[3257]: info: ----- Volumio3 ---- Jul 19 20:59:00 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:00 rivo volumio[3257]: info: ----- System startup ---- Jul 19 20:59:00 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:00 rivo smbd[3405]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 19 20:59:00 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 19 20:59:00 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 19 20:59:00 rivo ntpd[3240]: Soliciting pool server 202.68.92.244 Jul 19 20:59:00 rivo ntpd[3240]: Soliciting pool server 103.158.134.33 Jul 19 20:59:00 rivo ntpd[3240]: Soliciting pool server 103.242.68.68 Jul 19 20:59:01 rivo volumio[3257]: info: MYVOLUMIO Environment detected Jul 19 20:59:01 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 19 20:59:01 rivo systemd[1]: Started Daily apt upgrade and clean activities. Jul 19 20:59:01 rivo systemd[1]: Startup finished in 12.950s (kernel) + 25.118s (userspace) = 38.068s. Jul 19 20:59:01 rivo volumio[3257]: info: Plugin folders cleanup Jul 19 20:59:01 rivo volumio[3257]: info: Scanning into folder /volumio/app/plugins/ Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category audio_interface Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category miscellanea Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category music_service Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category plugins.json Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category system_controller Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category user_interface Jul 19 20:59:01 rivo volumio[3257]: info: Scanning into folder /data/plugins/ Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category audio_interface Jul 19 20:59:01 rivo volumio[3257]: info: Scanning category user_interface Jul 19 20:59:01 rivo volumio[3257]: info: Plugin folders cleanup completed Jul 19 20:59:01 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:01 rivo volumio[3257]: info: ----- Core plugins startup ---- Jul 19 20:59:01 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:01 rivo volumio[3257]: info: Loading plugins from folder /volumio/app/plugins/ Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin bluetooth to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin multiroom to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin upnp to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin metavolumio to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin cd_controller to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin raat to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 19 20:59:01 rivo volumio[3257]: info: Loading plugins from folder /data/plugins/ Jul 19 20:59:01 rivo volumio[3257]: info: Loading plugin "system"... Jul 19 20:59:01 rivo volumio[3257]: info: Loading plugin "appearance"... Jul 19 20:59:01 rivo ntpd[3240]: Soliciting pool server 103.242.70.4 Jul 19 20:59:01 rivo ntpd[3240]: Soliciting pool server 14.102.99.217 Jul 19 20:59:02 rivo ntpd[3240]: Soliciting pool server 2404:4408:6395:1060::123 Jul 19 20:59:03 rivo volumio[3257]: info: Loading plugin "network"... Jul 19 20:59:03 rivo volumio[3257]: info: Refreshing Cached IP Addresses Jul 19 20:59:03 rivo sudo[3489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:59:03 rivo sudo[3489]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:03 rivo sudo[3491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:59:03 rivo sudo[3491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:03 rivo sudo[3489]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:03 rivo volumio[3257]: info: Loading plugin "services"... Jul 19 20:59:03 rivo volumio[3257]: info: Loading plugin "alsa_controller"... Jul 19 20:59:03 rivo sudo[3491]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:03 rivo sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 19 20:59:03 rivo sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:03 rivo kernel: wl_escan_set_scan: ioctl_ver=2 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:03 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:03 rivo volumio[3257]: info: Loading plugin "wizard"... Jul 19 20:59:03 rivo volumio[3257]: info: Loading plugin "networkfs"... Jul 19 20:59:03 rivo volumio[3257]: info: Starting Udev Watcher for removable devices Jul 19 20:59:03 rivo sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.68.58/音乐 /mnt/NAS/Music Jul 19 20:59:03 rivo sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:04 rivo ntpd[3240]: receive: Unexpected origin timestamp 0xec25dcd7.cb4c7431 does not match aorg 0000000000.00000000 from server@103.158.134.33 xmt 0xec25dcd8.6a7ab37f Jul 19 20:59:04 rivo ntpd[3240]: receive: Unexpected origin timestamp 0xec25dcd7.cb58f0f0 does not match aorg 0000000000.00000000 from server@203.190.214.199 xmt 0xec25dcd8.6b88283c Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo volumio[3257]: info: Ignoring mount for partition: BOOT Jul 19 20:59:04 rivo volumio[3257]: info: Ignoring mount for partition: volumio Jul 19 20:59:04 rivo volumio[3257]: info: Ignoring mount for partition: volumio_data Jul 19 20:59:04 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:59:04 rivo volumio[3257]: info: Loading plugin "volumio_command_line_client"... Jul 19 20:59:04 rivo volumio[3257]: info: Loading plugin "upnp"... Jul 19 20:59:04 rivo volumio[3257]: info: [1752915544529] Starting Upmpd Daemon Jul 19 20:59:04 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:59:04 rivo volumio[3257]: info: Loading plugin "my_music"... Jul 19 20:59:04 rivo volumio[3257]: info: Loading plugin "mpd"... Jul 19 20:59:04 rivo kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.68.58\音乐 Jul 19 20:59:04 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jul 19 20:59:04 rivo sudo[3522]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:04 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo volumio[3257]: info: Loading plugin "upnp_browser"... Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:05 rivo volumio[3257]: info: Loading plugin "bluetooth"... Jul 19 20:59:05 rivo volumio-remote-updater[2830]: [2025-07-19 20:59:05] [connect] Successful connection Jul 19 20:59:05 rivo volumio[3257]: info: [1752915545916] Starting BluetoothController Jul 19 20:59:06 rivo volumio[3257]: info: Loading plugin "alarm-clock"... Jul 19 20:59:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:06 rivo volumio[3257]: info: Loading plugin "manifestui"... Jul 19 20:59:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:06 rivo volumio[3257]: info: Loading plugin "metavolumio"... Jul 19 20:59:06 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:07 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:07 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:08 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 8 Jul 19 20:59:08 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04da93000 drv_status 0x0 e_type 69 e_states 0 Jul 19 20:59:08 rivo sudo[3501]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:08 rivo volumio[3257]: info: Loading plugin "airplay_emulation"... Jul 19 20:59:08 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:08 rivo volumio[3257]: info: Loading plugin "cd_controller"... Jul 19 20:59:09 rivo volumio[3257]: info: Loading plugin "last_100"... Jul 19 20:59:09 rivo volumio[3257]: info: Loading plugin "qobuzconnect"... Jul 19 20:59:09 rivo volumio[3257]: info: Loading plugin "raat"... Jul 19 20:59:09 rivo volumio[3257]: info: RAAT Plugin loaded Jul 19 20:59:09 rivo volumio[3257]: info: Adding restartRAATSocket REST API Endpoint Jul 19 20:59:09 rivo volumio[3257]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 19 20:59:09 rivo volumio[3257]: info: Loading plugin "streaming_services"... Jul 19 20:59:10 rivo systemd[1]: systemd-fsckd.service: Succeeded. Jul 19 20:59:12 rivo volumio[3257]: info: Starting Streaming Service Transparent Proxy Jul 19 20:59:12 rivo volumio[3257]: info: Loading plugin "tidalconnect"... Jul 19 20:59:12 rivo volumio[3257]: info: Loading plugin "webradio"... Jul 19 20:59:12 rivo volumio[3257]: info: Loading plugin "i2s_dacs"... Jul 19 20:59:12 rivo volumio[3257]: info: I2S DAC not set, start Auto-detection Jul 19 20:59:12 rivo volumio[3257]: info: Loading plugin "volumiodiscovery"... Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 20:59:12 rivo node[3257]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 19 20:59:12 rivo node[3257]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** For more information see Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:59:12 rivo volumio[3257]: *** WARNING *** For more information see Jul 19 20:59:12 rivo node[3257]: *** WARNING *** For more information see Jul 19 20:59:12 rivo node[3257]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 19 20:59:12 rivo node[3257]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 19 20:59:12 rivo node[3257]: *** WARNING *** For more information see Jul 19 20:59:12 rivo volumio[3257]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 19 20:59:12 rivo volumio[3257]: info: Discovery: Started advertising with name: Rivo Jul 19 20:59:12 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:59:12 rivo volumio[3257]: info: Loading plugin "multiroom"... Jul 19 20:59:13 rivo volumio[3257]: STREAMING PROXY: Starting server on port 3245 Jul 19 20:59:13 rivo volumio[3257]: Node JS runtime: 14 Jul 19 20:59:15 rivo volumio[3257]: info: Applying required configuration parameters for plugin multiroom Jul 19 20:59:15 rivo sudo[3722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 19 20:59:15 rivo sudo[3722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:15 rivo sudo[3722]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:15 rivo volumio[3257]: info: MRS: MultiRoom plugin initialized Jul 19 20:59:15 rivo volumio[3257]: info: MRS: STOPPING SNAPCLIENT Jul 19 20:59:15 rivo volumio[3257]: info: MRS: Snap server stop Jul 19 20:59:15 rivo sudo[3738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 19 20:59:15 rivo volumio[3257]: info: MRS: STOPPING volumioStreaming Jul 19 20:59:15 rivo sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:15 rivo sudo[3740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 19 20:59:15 rivo sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:15 rivo volumio[3257]: info: Loading plugin "outputs"... Jul 19 20:59:15 rivo sudo[3738]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:15 rivo volumio[3257]: info: Loading plugin "albumart"... Jul 19 20:59:15 rivo sudo[3745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 19 20:59:15 rivo sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:15 rivo sudo[3746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 19 20:59:15 rivo sudo[3746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:15 rivo sudo[3740]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:15 rivo sudo[3746]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:15 rivo volumio[3257]: info: Plugin example_plugin is not enabled Jul 19 20:59:15 rivo volumio[3257]: info: Loading plugin "hi_res_audio"... Jul 19 20:59:15 rivo sudo[3745]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:16 rivo volumio[3257]: Forking 3 albumart workers Jul 19 20:59:17 rivo volumio[3257]: Starting albumart workers Jul 19 20:59:17 rivo volumio[3257]: Starting albumart workers Jul 19 20:59:17 rivo volumio[3257]: Starting albumart workers Jul 19 20:59:18 rivo volumio[3257]: info: Applying required configuration parameters for plugin hi_res_audio Jul 19 20:59:18 rivo volumio[3257]: info: Loading plugin "inputs"... Jul 19 20:59:19 rivo volumio[3257]: info: Loading plugin "qobuz"... Jul 19 20:59:20 rivo volumio-remote-updater[2830]: [2025-07-19 20:59:20] [connect] Successful connection Jul 19 20:59:21 rivo volumio[3257]: info: Loading plugin "smart_inputs"... Jul 19 20:59:21 rivo volumio[3257]: info: Loading plugin "tidal"... Jul 19 20:59:22 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Jul 19 20:59:23 rivo volumio[3257]: info: Loading plugin "rivocontrol"... Jul 19 20:59:23 rivo volumio[3257]: info: Adding this device properties Jul 19 20:59:23 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 19 20:59:23 rivo volumio[3257]: info: Setting Additional Device Volumio Properties: [object Object] Jul 19 20:59:23 rivo volumio[3257]: info: Loading plugin "updater_comm"... Jul 19 20:59:23 rivo volumio[3257]: info: Loading plugin "cec_controller"... Jul 19 20:59:24 rivo volumio[3257]: info: Applying required configuration parameters for plugin cec_controller Jul 19 20:59:24 rivo volumio[3257]: info: Plugin mpdemulation is not enabled Jul 19 20:59:24 rivo volumio[3257]: info: Loading plugin "rest_api"... Jul 19 20:59:24 rivo volumio[3257]: info: Loading plugin "websocket"... Jul 19 20:59:24 rivo volumio[3257]: info: Starting Socket.io Server version 2.3.0 Jul 19 20:59:24 rivo volumio[3257]: info: Loading plugin "fusiondsp"... Jul 19 20:59:24 rivo volumio[3257]: info: Applying required configuration parameters for plugin fusiondsp Jul 19 20:59:24 rivo volumio[3257]: info: Loading plugin "lastfm"... Jul 19 20:59:25 rivo volumio[3257]: info: Loading plugin "randomizer"... Jul 19 20:59:26 rivo volumio[3257]: info: Applying required configuration parameters for plugin randomizer Jul 19 20:59:26 rivo volumio[3257]: info: Loading i18n strings for locale zh Jul 19 20:59:26 rivo volumio[3257]: Updating browse sources language Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:26 rivo kernel: fb: mem_free_work, free memory: addr:800000 Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::initPlayerControls Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:26 rivo volumio[3257]: Express server listening on port 3000 Jul 19 20:59:26 rivo volumio[3257]: [Metrics] WebUI: 27s 277.03ms Jul 19 20:59:26 rivo volumio[3257]: info: CoreStateMachine::resetVolumioState Jul 19 20:59:26 rivo volumio[3257]: info: CoreStateMachine::getcurrentVolume Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:59:26 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:26 rivo sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:59:26 rivo sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:26 rivo sudo[3826]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:26 rivo sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:59:26 rivo volumio[3257]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 19 20:59:26 rivo sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:26 rivo volumio[3257]: info: Completed loading Core Plugins Jul 19 20:59:26 rivo volumio[3257]: info: Preparing to generate the ALSA configuration file Jul 19 20:59:26 rivo sudo[3829]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:26 rivo volumio[3257]: info: Volumio Network Manager: Network status updated: 2 Jul 19 20:59:26 rivo volumio[3257]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 19 20:59:26 rivo volumio[3257]: info: MRS: Removed streaming files Jul 19 20:59:26 rivo volumio[3257]: info: MRS: volumioStreaming STOPPED Jul 19 20:59:26 rivo volumio[3257]: info: MRS: SNAPSERVER STOPPED Jul 19 20:59:26 rivo volumio[3257]: info: MRS: SNAPCLIENT STOPPED Jul 19 20:59:26 rivo volumio[3257]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds Jul 19 20:59:26 rivo volumio[3257]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 19 20:59:26 rivo volumio[3257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 20:59:26 rivo volumio[3257]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 20:59:26 rivo volumio[3257]: info: Reading ALSA contributions from plugins. Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:27 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:27 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 19 20:59:27 rivo volumio[3257]: info: VolumeController:: Volume=undefined Mute =false Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::updateTrackBlock Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrackBlock Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioRetrievevolume Jul 19 20:59:27 rivo volumio[3257]: info: Reloading queue from file Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::setRepeat null single undefined Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::setRandom false Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:27 rivo volumio[3257]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 19 20:59:27 rivo volumio[3257]: info: Discovery: adding e3fdb852-a15b-48c9-8fe5-ac71ed102448 Jul 19 20:59:27 rivo volumio[3257]: info: Discovery: Found device Rivo Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: MRS: Pushing multiroomSync output for this device Jul 19 20:59:27 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:27 rivo systemd[1]: systemd-timedated.service: Succeeded. Jul 19 20:59:27 rivo sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 19 20:59:27 rivo sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:27 rivo volumio[3257]: info: Adding audio output: Jul 19 20:59:27 rivo volumio[3257]: info: Adding audio output: Jul 19 20:59:27 rivo volumio[3257]: info: VolumeController:: Volume=undefined Mute =false Jul 19 20:59:27 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:27 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Jul 19 20:59:27 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:27 rivo sudo[3848]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:27 rivo volumio[3257]: info: Upmpdcli Daemon Started Jul 19 20:59:27 rivo volumio[3257]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:59:27 rivo volumio[3257]: info: Output device has changed, restarting MPD Jul 19 20:59:27 rivo sudo[3864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:27 rivo sudo[3864]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:27 rivo volumio[3257]: info: Output device has changed, restarting Shairport Sync Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:27 rivo sudo[3864]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:27 rivo sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:27 rivo sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:27 rivo systemd[1]: Stopping Music Player Daemon... Jul 19 20:59:27 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:27 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:27 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:27 rivo volumio[3257]: info: ___________ START PLUGINS ___________ Jul 19 20:59:27 rivo volumio[3257]: info: ControllerMpd::onStart: Initializing MPD Jul 19 20:59:27 rivo volumio[3257]: info: Creating MPD Configuration file Jul 19 20:59:28 rivo sudo[3875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:28 rivo sudo[3875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo sudo[3875]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:28 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:59:28 rivo volumio[3257]: info: [1752915568064] CoreMusicLibrary::Adding element 媒体服务器 Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:28 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 19 20:59:28 rivo sudo[3878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:28 rivo sudo[3878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo volumio[3257]: info: Adding METAVOLUMIO REST API Endpoints Jul 19 20:59:28 rivo volumio[3257]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 19 20:59:28 rivo volumio[3257]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 19 20:59:28 rivo volumio[3257]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 19 20:59:28 rivo volumio[3257]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:28 rivo sudo[3882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Jul 19 20:59:28 rivo sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 19 20:59:28 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:28 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:28 rivo sudo[3882]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:28 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:28 rivo volumio[3257]: info: Preparing CD Folders Jul 19 20:59:28 rivo volumio[3257]: info: Adding CD REST API Endpoints Jul 19 20:59:28 rivo volumio[3257]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 19 20:59:28 rivo volumio[3257]: info: Starting UDEV Watcher for CD Jul 19 20:59:28 rivo volumio[3257]: info: Detecting CD presence with UDEV Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:59:28 rivo volumio[3257]: info: [1752915568350] CoreMusicLibrary::Adding element Last_100 Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:28 rivo volumio[3257]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 19 20:59:28 rivo sudo[3887]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 20:59:28 rivo sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo sudo[3887]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo volumio[3257]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 19 20:59:28 rivo volumio[3257]: info: Starting RAAT Plugin Jul 19 20:59:28 rivo sudo[3897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 20:59:28 rivo sudo[3897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 19 20:59:28 rivo volumio[3257]: info: Additional UI Settings Added for plugin music_service/raat Jul 19 20:59:28 rivo volumio[3257]: info: Registering DSP Elements listener and retrieving current ones Jul 19 20:59:28 rivo volumio[3257]: info: Additional DSP elements updated Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:28 rivo volumio[3257]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 19 20:59:28 rivo volumio[3257]: info: Streaming services startup Jul 19 20:59:28 rivo volumio[3257]: info: Starting Streaming Daemon Jul 19 20:59:28 rivo sudo[3897]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo sudo[3902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 20:59:28 rivo sudo[3902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 19 20:59:28 rivo sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 20:59:28 rivo sudo[3902]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo sudo[3903]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:59:28 rivo volumio[3257]: info: [1752915568689] CoreMusicLibrary::Adding element Webradio Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:28 rivo volumio[3257]: info: Initializing BBC Radios Jul 19 20:59:28 rivo sudo[3913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:28 rivo sudo[3913]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:28 rivo sudo[3913]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 19 20:59:28 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:28 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:28 rivo volumio[3257]: error: Hi Res Audio Failed Login: Missing Login Data Jul 19 20:59:28 rivo volumio[3257]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 19 20:59:28 rivo volumio[3257]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 19 20:59:28 rivo volumio[3257]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 19 20:59:28 rivo volumio[3257]: info: Touch Event Listener Process Starting Jul 19 20:59:28 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 19 20:59:28 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 19 20:59:28 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 19 20:59:28 rivo volumio[3257]: info: Adding inputs REST Endpoints Jul 19 20:59:28 rivo volumio[3257]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 19 20:59:28 rivo volumio[3257]: info: Scanning Audio Inputs Jul 19 20:59:29 rivo sudo[3932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 19 20:59:29 rivo sudo[3932]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo volumio[3257]: info: Checking against Known Cards name Jul 19 20:59:29 rivo volumio[3257]: info: Checking against Known Cards name Jul 19 20:59:29 rivo volumio[3257]: info: Checking against Known Cards name Jul 19 20:59:29 rivo volumio[3257]: info: Checking against Known Cards name Jul 19 20:59:29 rivo volumio[3257]: info: Checking against Known Cards name Jul 19 20:59:29 rivo volumio[3257]: info: Adding Server instance for streaming Jul 19 20:59:29 rivo sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 19 20:59:29 rivo sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo sudo[3943]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 19 20:59:29 rivo sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo sudo[3946]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo sudo[3949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 19 20:59:29 rivo sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo sudo[3949]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo sudo[3952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 19 20:59:29 rivo sudo[3952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo sudo[3952]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo volumio[3257]: info: Apply VIM3L Onboard LEDs Settings Jul 19 20:59:29 rivo volumio[3257]: info: Turning On Onboard LEDs Jul 19 20:59:29 rivo sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 19 20:59:29 rivo sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo volumio[3257]: info: Starting CEC Event listener Jul 19 20:59:29 rivo sudo[3956]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo volumio[3257]: info: Loading i18n strings for locale zh Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:29 rivo volumio[3257]: info: FusionDsp - mixtype--------------------- Hardware Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:59:29 rivo volumio[3257]: info: [1752915569597] CoreMusicLibrary::Adding element LastFM Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:29 rivo sudo[3960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 19 20:59:29 rivo sudo[3960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:29 rivo volumio[3257]: Cannot find translation for source LastFM Jul 19 20:59:29 rivo sudo[3960]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] scrobbler initiated! Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] extended logging: false Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] try scrobble stream/radio plays: true Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] Left init routine Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] Socket already connected: true Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 19 20:59:29 rivo volumio[3257]: info: [1752915569726] CoreMusicLibrary::Adding element Randomizer Jul 19 20:59:29 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:29 rivo volumio[3257]: Cannot find translation for source LastFM Jul 19 20:59:29 rivo volumio[3257]: Cannot find translation for source Randomizer Jul 19 20:59:29 rivo volumio[3257]: info: Volumio Calling Home Jul 19 20:59:29 rivo volumio[3257]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 19 20:59:29 rivo volumio[3257]: (node:3257) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 19 20:59:29 rivo volumio[3257]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 19 20:59:29 rivo volumio[3257]: info: Preparing to generate the ALSA configuration file Jul 19 20:59:29 rivo volumio[3257]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jul 19 20:59:29 rivo volumio[3257]: info: Stopping AccessToken refresher cron for QOBUZ Jul 19 20:59:30 rivo volumio[3257]: info: AccessToken refresher cron started for QOBUZ Jul 19 20:59:30 rivo volumio[3257]: info: Stopping AccessToken refresher cron Jul 19 20:59:30 rivo volumio[3257]: info: AccessToken refresher cron started Jul 19 20:59:30 rivo volumio[3257]: info: Adding TIDAL REST API Endpoints Jul 19 20:59:30 rivo volumio[3257]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 19 20:59:30 rivo volumio[3257]: info: Adding QOBUZ REST API Endpoints Jul 19 20:59:30 rivo volumio[3257]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 19 20:59:30 rivo volumio[3257]: info: Serial port opened successfully Jul 19 20:59:30 rivo volumio[3257]: info: Sending serial start messages Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: Reporting MCU Network Status: 2 Jul 19 20:59:30 rivo volumio[3257]: info: MP1 GPIO: Signalled system ready via GPIO Jul 19 20:59:30 rivo volumio[3257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 20:59:30 rivo volumio[3257]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 20:59:30 rivo volumio[3257]: info: Reading ALSA contributions from plugins. Jul 19 20:59:30 rivo volumio[3257]: info: Executing endpoint qc_getconfig Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.447 [3909.3909] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 20:59:30 rivo volumio[3257]: error: Cannot start Volumio Streaming Daemon Jul 19 20:59:30 rivo volumio[3257]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 19 20:59:30 rivo volumio[3257]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 19 20:59:30 rivo volumio[3257]: info: RAAT Albumart path created successfully Jul 19 20:59:30 rivo volumio[3257]: info: Bluetooth name changed to Rivo Jul 19 20:59:30 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:30 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 19 20:59:30 rivo volumio[3257]: info: Setting Device Volume Override Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 19 20:59:30 rivo volumio[3257]: info: Updating Volume Controller Parameters: Device: 5 Name: 32bit 384kHz DSD Interface Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 19 20:59:30 rivo volumio[3257]: info: Disabling external Volume Control Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.559 [3909.3909] INFO VolumeManager: [0xacaa1868]: Setting new playback volume: 75 Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.559 [3909.3909] INFO VolumeManager: [0xacaa1868]: Setting new mute state: 0 Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.559 [3909.3909] INFO QobuzConnect: [0xacaa2238]: Client initialized! Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.560 [3909.3909] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.573 [3909.3909] INFO LocalConfigManager: [0xacaa1148]: Starting Local Configuration server Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.574 [3909.3909] INFO SampleApp: Starting Local configuration server Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.575 [3909.3909] INFO SampleApp: Connected to UNIX socket client 0xaca96ed8 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:30 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:30 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:30 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:30 rivo qobuz-connect[3909]: 20250719 20:59:30.597 [3909.3909] INFO SampleApp: Playback volume changed: 75 Jul 19 20:59:30 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:30 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 20:59:30 rivo volumio[3257]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 19 20:59:30 rivo volumio[3257]: info: Setting Additional System Software info: Hardware Revision: 2.0 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 19 20:59:30 rivo volumio[3257]: info: Setting HW Firmware info: undefined Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 19 20:59:30 rivo volumio[3257]: info: Setting HW Version info: 2.0 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 19 20:59:30 rivo volumio[3257]: info: Setting Additional System Software info: Hardware Revision: 2.0, Firmware Version: 0.3.4 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 19 20:59:30 rivo volumio[3257]: info: Setting HW Firmware info: 0.3.4 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 19 20:59:30 rivo volumio[3257]: info: Setting HW Version info: 2.0 Jul 19 20:59:30 rivo volumio[3257]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 19 20:59:30 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 20:59:30 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:30 rivo volumio[3257]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 19 20:59:30 rivo volumio[3257]: info: MCU Signalled Sleep Mode Disabled Jul 19 20:59:30 rivo volumio[3257]: info: Enabling Advanced system settings configuration Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 19 20:59:30 rivo volumio[3257]: info: Additional UI Settings Added for plugin music_service/inputs Jul 19 20:59:30 rivo volumio[3257]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 19 20:59:30 rivo sudo[3984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 19 20:59:30 rivo sudo[3984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:30 rivo kernel: fb: osd[0] enable: 1 (Xorg) Jul 19 20:59:30 rivo sudo[3984]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:30 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 19 20:59:30 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:30 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:30 rivo volumio[3257]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Jul 19 20:59:30 rivo mpd[3895]: Jul 19 20:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:30 rivo systemd[1]: Started Music Player Daemon. Jul 19 20:59:30 rivo sudo[3878]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:30 rivo sudo[3866]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 19 20:59:31 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 19 20:59:31 rivo sudo[4004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 19 20:59:31 rivo sudo[4004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:31 rivo sudo[4004]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:31 rivo sudo[4007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 19 20:59:31 rivo sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:31 rivo volumio[3257]: Upnp client error: Error: This socket has been ended by the other party Jul 19 20:59:31 rivo systemd[1]: Started Volumio Bluetooth Module. Jul 19 20:59:31 rivo sudo[4007]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:31 rivo sudo[4010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 19 20:59:31 rivo sudo[4010]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:31 rivo volumiobt[4009]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 19 20:59:31 rivo sudo[4010]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:31 rivo volumio[3257]: info: VolumeController:: Volume=undefined Mute =false Jul 19 20:59:31 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:31 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:31 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:31 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:31 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:31 rivo volumio[3257]: info: Volumio called home Jul 19 20:59:31 rivo volumio[3257]: info: Volumio BT Module successfully started Jul 19 20:59:31 rivo volumio[3257]: info: camilladsp spawned new process with pid 4021, instance 1, run: true Jul 19 20:59:31 rivo volumio[3257]: info: camilladsp service started and running in background, instance 1 Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: No protocol specified Jul 19 20:59:31 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:31 rivo volumio[3257]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jul 19 20:59:31 rivo volumio[3257]: info: FusionDsp loaded Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:31 rivo sudo[4031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 19 20:59:31 rivo sudo[4031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:31 rivo volumio[3257]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 19 20:59:31 rivo volumio[3257]: info: Adding Signal Path Element [object Object] Jul 19 20:59:31 rivo volumio[3257]: info: Adding fusiondspeq DSP Signal Path Element Jul 19 20:59:31 rivo volumio[3257]: info: Additional DSP elements updated Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:31 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:31 rivo volumio[3257]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 19 20:59:31 rivo volumio[3257]: info: FusionDsp - ---- installed callbackRead Jul 19 20:59:31 rivo volumio[3257]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 20:59:31 rivo systemd[1]: Started FusionDsp Daemon. Jul 19 20:59:31 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:31 rivo sudo[4031]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:31 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:31 rivo sudo[4036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:31 rivo sudo[4036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:31 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:32 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:59:32 rivo sudo[4039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:32 rivo sudo[4039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:59:32 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:32 rivo volumio[3257]: info: Adding Inputs via Serial API Jul 19 20:59:32 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:32 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:32 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:32 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:32 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:32 rivo sudo[4042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:32 rivo sudo[4042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:32 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:32 rivo sudo[4036]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo sudo[4039]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:32 rivo sudo[4042]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo volumio[3257]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:59:32 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:32 rivo volumio[3257]: error: The expression evaluated to a falsy value: Jul 19 20:59:32 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:32 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:32 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:32 rivo volumio[3257]: info: MPD running with PID3895 Jul 19 20:59:32 rivo volumio[3257]: ,establishing connection Jul 19 20:59:32 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:32 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:32 rivo sudo[4053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.68.58/音乐 /mnt/NAS/Music Jul 19 20:59:32 rivo sudo[4053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo volumio[3257]: error: updateQueue error: null Jul 19 20:59:32 rivo sudo[4054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 19 20:59:32 rivo sudo[4054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo volumiobt[4009]: pulseaudio: no process found Jul 19 20:59:32 rivo kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.68.58\音乐 Jul 19 20:59:32 rivo sudo[4054]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:32 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:32 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jul 19 20:59:32 rivo sudo[4053]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 19 20:59:32 rivo dbus-daemon[2822]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=4063 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jul 19 20:59:32 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:32 rivo volumio[3257]: Error adding Membership: Error: addMembership EINVAL Jul 19 20:59:32 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:32 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:32 rivo volumio[3257]: info: Turning Off Onboard LEDs Jul 19 20:59:32 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 19 20:59:32 rivo kernel: IPVS: Creating netns size=1928 id=3 Jul 19 20:59:32 rivo volumio[3257]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:59:32 rivo volumio[3257]: info: Output device has changed, restarting MPD Jul 19 20:59:32 rivo dbus-daemon[2822]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 19 20:59:32 rivo sudo[4071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Jul 19 20:59:32 rivo rtkit-daemon[4068]: Successfully called chroot. Jul 19 20:59:32 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 19 20:59:32 rivo rtkit-daemon[4068]: Successfully dropped privileges. Jul 19 20:59:32 rivo rtkit-daemon[4068]: Successfully limited resources. Jul 19 20:59:32 rivo rtkit-daemon[4068]: Running. Jul 19 20:59:32 rivo rtkit-daemon[4068]: Canary thread running. Jul 19 20:59:32 rivo sudo[4071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo dbus-daemon[2822]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=4068 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jul 19 20:59:32 rivo rtkit-daemon[4068]: Watchdog thread running. Jul 19 20:59:32 rivo sudo[4071]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo systemd[1]: Starting Authorization Manager... Jul 19 20:59:32 rivo sudo[4076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:32 rivo sudo[4076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo sudo[4078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Jul 19 20:59:32 rivo volumio[3257]: info: Output device has changed, restarting Shairport Sync Jul 19 20:59:32 rivo sudo[4078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:32 rivo sudo[4076]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:32 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:32 rivo sudo[4078]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:33 rivo sudo[4082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:33 rivo sudo[4082]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:33 rivo polkitd[4079]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 19 20:59:33 rivo systemd[1]: Stopping Music Player Daemon... Jul 19 20:59:33 rivo dbus-daemon[2822]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 19 20:59:33 rivo systemd[1]: Started Authorization Manager. Jul 19 20:59:33 rivo volumio[3257]: No protocol specified Jul 19 20:59:33 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:33 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:33 rivo volumio[3257]: No protocol specified Jul 19 20:59:33 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:33 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:33 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:33 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:33 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:33 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:33 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:33 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:33 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:33 rivo volumio[3257]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 20:59:33 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:33 rivo volumio[3257]: info: Preparing to generate the ALSA configuration file Jul 19 20:59:33 rivo sudo[4092]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 20:59:33 rivo sudo[4092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:33 rivo sudo[4092]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:33 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:33 rivo sudo[4099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 20:59:33 rivo sudo[4099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:33 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:33 rivo sudo[4099]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:33 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:33 rivo sudo[4103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 20:59:33 rivo sudo[4103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:33 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:33 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:33 rivo qobuz-connect[3909]: 20250719 20:59:33.747 [3909.3909] INFO SampleApp: Stopping Local configuration server Jul 19 20:59:33 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 20:59:33 rivo qobuz-connect[3909]: 20250719 20:59:33.758 [3909.3909] INFO SampleApp: shat down connection on UNIX socket Jul 19 20:59:33 rivo systemd[1]: qobuz-connect.service: Succeeded. Jul 19 20:59:33 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 20:59:33 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 20:59:33 rivo volumio[3257]: error: MPD error: Error: write EPIPE Jul 19 20:59:33 rivo volumio[3257]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Jul 19 20:59:33 rivo sudo[4103]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:33 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 19 20:59:33 rivo volumio[3257]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:33 rivo volumio[3257]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:33 rivo volumio[3257]: info: Cannot mount NAS Music at system boot, trial number 2 ,retrying in 5 seconds Jul 19 20:59:33 rivo pulseaudio[4063]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 19 20:59:33 rivo pulseaudio[4063]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 19 20:59:34 rivo volumiobt[4009]: Applying permissions Jul 19 20:59:34 rivo volumiobt[4009]: Setting BT discoverable and pairable Jul 19 20:59:34 rivo bluetoothd[3183]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Jul 19 20:59:34 rivo bluetoothd[3183]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Jul 19 20:59:34 rivo pulseaudio[4063]: 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 Jul 19 20:59:34 rivo sudo[4111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 20:59:34 rivo sudo[4111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:34 rivo systemd[1]: Started Volumio Tidal Connect Service. Jul 19 20:59:34 rivo volumiobt[4009]: Agent registered Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# -e power on Jul 19 20:59:34 rivo volumiobt[4009]: Invalid command in menu main: -e Jul 19 20:59:34 rivo volumiobt[4009]: Jul 19 20:59:34 rivo volumiobt[4009]: Use "help" for a list of available commands in a menu. Jul 19 20:59:34 rivo volumiobt[4009]: Use "menu " if you want to enter any submenu. Jul 19 20:59:34 rivo volumiobt[4009]: Use "back" if you want to return to menu main. Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# agent on Jul 19 20:59:34 rivo volumiobt[4009]: Agent is already registered Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# discoverable on Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# pairable on Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# agent NoInputNoOutput Jul 19 20:59:34 rivo volumiobt[4009]: Agent is already registered Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# default-agent Jul 19 20:59:34 rivo volumiobt[4009]: [bluetooth]# quit Jul 19 20:59:34 rivo sudo[4111]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:34 rivo volumiobt[4009]: [59B blob data] Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: Not Reporting Auto name since its the default one Jul 19 20:59:34 rivo volumio[3257]: info: MRS: Getting audio outputs on start Jul 19 20:59:34 rivo volumio[3257]: info: MRS: Requesting all other devices output Jul 19 20:59:34 rivo volumio[3257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 20:59:34 rivo volumio[3257]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 20:59:34 rivo volumio[3257]: info: Reading ALSA contributions from plugins. Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::servicePushState Jul 19 20:59:34 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:34 rivo volumio[3257]: 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} Jul 19 20:59:34 rivo volumio[3257]: verbose: CURRENT POSITION 0 Jul 19 20:59:34 rivo volumio[3257]: info: CoreStateMachine::syncState stateService play Jul 19 20:59:34 rivo volumio[3257]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:34 rivo volumio[3257]: Cannot find translation for source LastFM Jul 19 20:59:34 rivo volumio[3257]: Cannot find translation for source Randomizer Jul 19 20:59:34 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:34 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:34 rivo volumio[3257]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 19 20:59:34 rivo volumio[3257]: error: Upnp client error: Error: This socket has been ended by the other party Jul 19 20:59:34 rivo volumio[3257]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 20:59:34 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:34 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 20:59:34 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:34 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:34 rivo volumio[3257]: info: Onboard LEDs initialized Jul 19 20:59:34 rivo volumio[3257]: info: Executing endpoint qc_getconfig Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.769 [4105.4105] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.781 [4105.4105] INFO VolumeManager: [0xab40c868]: Setting new playback volume: 75 Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.782 [4105.4105] INFO VolumeManager: [0xab40c868]: Setting new mute state: 0 Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.782 [4105.4105] INFO QobuzConnect: [0xab40d238]: Client initialized! Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.782 [4105.4105] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.802 [4105.4105] INFO LocalConfigManager: [0xab40c148]: Starting Local Configuration server Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.803 [4105.4105] INFO SampleApp: Starting Local configuration server Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.804 [4105.4105] INFO SampleApp: Connected to UNIX socket client 0xab401ed8 Jul 19 20:59:34 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 19 20:59:34 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 20:59:34 rivo volumio[3257]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo qobuz-connect[4105]: 20250719 20:59:34.919 [4105.4105] INFO SampleApp: Playback volume changed: 75 Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:34 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 20:59:35 rivo volumio[3257]: No protocol specified Jul 19 20:59:35 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:35 rivo sudo[4151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 20:59:35 rivo sudo[4151]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:35 rivo sudo[4151]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:35 rivo sudo[4153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 20:59:35 rivo sudo[4153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:35 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:35 rivo systemd[1]: Started RAAT DAEMON. Jul 19 20:59:35 rivo sudo[4153]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:35 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 19 20:59:35 rivo volumio[3257]: info: Executing endpoint tc_getconfig Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 19 20:59:35 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:35 rivo vtcs[4115]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 19 20:59:35 rivo vtcs[4115]: STARTED TidalConnect services. Jul 19 20:59:35 rivo sudo[4172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:35 rivo sudo[4172]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:35 rivo volumio[3257]: 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 Jul 19 20:59:35 rivo volumio[3257]: info: Raat Daemon started successfully Jul 19 20:59:35 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:59:35 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:59:35 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:35 rivo volumio[3257]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:59:35 rivo volumio[3257]: info: Output device has changed, restarting MPD Jul 19 20:59:35 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:35 rivo sudo[4172]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:35 rivo volumio-remote-updater[2830]: [2025-07-19 20:59:35] [connect] Successful connection Jul 19 20:59:35 rivo volumio[3257]: info: Output device has changed, restarting Shairport Sync Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:35 rivo sudo[4178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:35 rivo sudo[4178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:35 rivo sudo[4178]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:35 rivo sudo[4181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:35 rivo sudo[4181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:35 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:35 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:35 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:35 rivo volumio[3257]: No protocol specified Jul 19 20:59:35 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:35 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:35 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:35 rivo volumio[3257]: No protocol specified Jul 19 20:59:35 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:35 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:35 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:35 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:35 rivo volumio[3257]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 20:59:35 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: error: Error starting TidalConnect Cannot read property 'sendRevoke' of undefined {"stack":"TypeError: Cannot read property 'sendRevoke' of undefined\n at ControllerTidalconnect.stopTidalConnect (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8526)\n at ControllerTidalconnect.restartService (/volumio/app/plugins/music_service/tidalconnect/tidalconnect_real:1:8818)\n at /volumio/node_modules/v-conf/index.js:165:13\n at /volumio/node_modules/multimap/index.js:150:9\n at Array.forEach ()\n at /volumio/node_modules/multimap/index.js:149:13\n at mapEach (/volumio/node_modules/multimap/index.js:9:5)\n at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:143:5)\n at Multimap.forEach (/volumio/node_modules/multimap/index.js:148:10)\n at Config.set (/volumio/node_modules/v-conf/index.js:162:20)\n at Promise._successFn (/volumio/app/plugins/audio_interface/alsa_controller/index.js:2107:39)\n at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)\n at processTicksAndRejections (internal/process/task_queues.js:77:11)"} Jul 19 20:59:36 rivo volumio[3257]: info: Preparing to generate the ALSA configuration file Jul 19 20:59:36 rivo sudo[4193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:36 rivo sudo[4188]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 20:59:36 rivo sudo[4188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:36 rivo sudo[4193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:36 rivo sudo[4188]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:36 rivo systemd[1]: Stopping Volumio Tidal Connect Service... Jul 19 20:59:36 rivo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:36 rivo systemd[1]: vtcs.service: Succeeded. Jul 19 20:59:36 rivo systemd[1]: Stopped Volumio Tidal Connect Service. Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:36 rivo sudo[4193]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:36 rivo sudo[4201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 20:59:36 rivo sudo[4201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:36 rivo sudo[4201]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:36 rivo sudo[4204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 20:59:36 rivo sudo[4204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:36 rivo volumio[3257]: info: Executing endpoint tc_connect Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 19 20:59:36 rivo volumio[3257]: info: Connecting to TidalConnect Jul 19 20:59:36 rivo volumio[3257]: info: Executing endpoint restartRAATSocket Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 20:59:36 rivo volumio[3257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 20:59:36 rivo volumio[3257]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 20:59:36 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 20:59:36 rivo volumio[3257]: info: Reading ALSA contributions from plugins. Jul 19 20:59:36 rivo qobuz-connect[4105]: 20250719 20:59:36.386 [4105.4105] INFO SampleApp: Stopping Local configuration server Jul 19 20:59:36 rivo qobuz-connect[4105]: 20250719 20:59:36.396 [4105.4105] INFO SampleApp: shat down connection on UNIX socket Jul 19 20:59:36 rivo systemd[1]: qobuz-connect.service: Succeeded. Jul 19 20:59:36 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:36 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:36 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 20:59:36 rivo sudo[4204]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:36 rivo volumio[3257]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 20:59:36 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:36 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:36 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:36 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:36 rivo volumio-remote-updater[2830]: [2025-07-19 20:59:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752915575 101 Jul 19 20:59:36 rivo volumio[3257]: 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: 7 Jul 19 20:59:36 rivo volumio[3257]: info: Signalling Playback active due to playback status change Jul 19 20:59:36 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 20:59:36 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:36 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: Not Reporting Auto name since its the default one Jul 19 20:59:36 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:36 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 19 20:59:36 rivo volumio[3257]: info: Executing endpoint qc_getconfig Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.836 [4207.4207] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.853 [4207.4207] INFO VolumeManager: [0xac3a0868]: Setting new playback volume: 75 Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.853 [4207.4207] INFO VolumeManager: [0xac3a0868]: Setting new mute state: 0 Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.853 [4207.4207] INFO QobuzConnect: [0xac3a1238]: Client initialized! Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.853 [4207.4207] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.894 [4207.4207] INFO LocalConfigManager: [0xac3a0148]: Starting Local Configuration server Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.895 [4207.4207] INFO SampleApp: Starting Local configuration server Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.895 [4207.4207] INFO SampleApp: Connected to UNIX socket client 0xac395ed8 Jul 19 20:59:36 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 20:59:36 rivo volumio[3257]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 20:59:36 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 20:59:36 rivo qobuz-connect[4207]: 20250719 20:59:36.986 [4207.4207] INFO SampleApp: Playback volume changed: 75 Jul 19 20:59:36 rivo volumio[3257]: No protocol specified Jul 19 20:59:36 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:37 rivo volumio[3257]: info: MCU Signalled Playback Active Jul 19 20:59:37 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:37 rivo sudo[4223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 20:59:37 rivo sudo[4223]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4223]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:37 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:37 rivo sudo[4227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 20:59:37 rivo sudo[4226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:37 rivo sudo[4227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo volumio[3257]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:59:37 rivo volumio[3257]: info: Output device has changed, restarting MPD Jul 19 20:59:37 rivo systemd[1]: Stopping RAAT DAEMON... Jul 19 20:59:37 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:59:37 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:37 rivo systemd[1]: raat-daemon.service: Succeeded. Jul 19 20:59:37 rivo systemd[1]: Stopped RAAT DAEMON. Jul 19 20:59:37 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:59:37 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:37 rivo sudo[4231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:37 rivo sudo[4231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4231]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo volumio[3257]: info: Output device has changed, restarting Shairport Sync Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:37 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:37 rivo sudo[4234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:37 rivo sudo[4234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4226]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo systemd[1]: Started RAAT DAEMON. Jul 19 20:59:37 rivo sudo[4227]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:37 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:37 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:37 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:37 rivo volumio[3257]: No protocol specified Jul 19 20:59:37 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:37 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:37 rivo volumio[3257]: No protocol specified Jul 19 20:59:37 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:37 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:37 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:37 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:37 rivo volumio[3257]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 20:59:37 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:37 rivo volumio[3257]: info: Preparing to generate the ALSA configuration file Jul 19 20:59:37 rivo sudo[4259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:37 rivo sudo[4259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 20:59:37 rivo sudo[4255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4259]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo sudo[4255]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo sudo[4264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:37 rivo sudo[4264]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:37 rivo sudo[4264]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:37 rivo volumio[3257]: info: TidalConnect service started! Jul 19 20:59:37 rivo volumio[3257]: info: Completed starting Core Plugins Jul 19 20:59:37 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:37 rivo volumio[3257]: info: ----- MyVolumio plugins startup ---- Jul 19 20:59:37 rivo volumio[3257]: info: ------------------------------------------- Jul 19 20:59:37 rivo volumio[3257]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 19 20:59:37 rivo sudo[4272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 20:59:37 rivo sudo[4272]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:38 rivo volumio[3257]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 19 20:59:38 rivo volumio[3257]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 19 20:59:38 rivo volumio[3257]: info: Reading ALSA contributions from plugins. Jul 19 20:59:38 rivo sudo[4272]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:38 rivo sudo[4274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 20:59:38 rivo sudo[4274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:38 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 20:59:38 rivo qobuz-connect[4207]: 20250719 20:59:38.134 [4207.4207] INFO SampleApp: Stopping Local configuration server Jul 19 20:59:38 rivo qobuz-connect[4207]: 20250719 20:59:38.145 [4207.4207] INFO SampleApp: shat down connection on UNIX socket Jul 19 20:59:38 rivo systemd[1]: qobuz-connect.service: Succeeded. Jul 19 20:59:38 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 20:59:38 rivo volumio[3257]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 20:59:38 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:38 rivo volumio[3257]: info: Raat Daemon started successfully Jul 19 20:59:38 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:38 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 20:59:38 rivo sudo[4274]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: Not Reporting Auto name since its the default one Jul 19 20:59:38 rivo volumio[3257]: info: RAAT: Requesting Headphone Status Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:38 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:38 rivo volumio[3257]: info: Executing endpoint restartRAATSocket Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 20:59:38 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 20:59:38 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:38 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 20:59:38 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:38 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:38 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:38 rivo volumio[3257]: info: Executing endpoint qc_getconfig Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.628 [4276.4276] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.636 [4276.4276] INFO VolumeManager: [0xabacc868]: Setting new playback volume: 75 Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.638 [4276.4276] INFO VolumeManager: [0xabacc868]: Setting new mute state: 0 Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.638 [4276.4276] INFO QobuzConnect: [0xabacd238]: Client initialized! Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.638 [4276.4276] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.683 [4276.4276] INFO LocalConfigManager: [0xabacc148]: Starting Local Configuration server Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.684 [4276.4276] INFO SampleApp: Starting Local configuration server Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.685 [4276.4276] INFO SampleApp: Connected to UNIX socket client 0xabac1ed8 Jul 19 20:59:38 rivo volumio[3257]: No protocol specified Jul 19 20:59:38 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:38 rivo qobuz-connect[4276]: 20250719 20:59:38.774 [4276.4276] INFO SampleApp: Playback volume changed: 75 Jul 19 20:59:38 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 20:59:38 rivo volumio[3257]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 20:59:38 rivo sudo[4294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 20:59:38 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 19 20:59:38 rivo sudo[4294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:38 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:38 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:38 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:38 rivo sudo[4294]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo sudo[4298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:39 rivo sudo[4298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo sudo[4297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 20:59:39 rivo sudo[4297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo volumio[3257]: info: Asound.conf file unchanged, so no further update is needed Jul 19 20:59:39 rivo volumio[3257]: info: Output device has changed, restarting MPD Jul 19 20:59:39 rivo systemd[1]: Stopping RAAT DAEMON... Jul 19 20:59:39 rivo sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.68.58/音乐 /mnt/NAS/Music Jul 19 20:59:39 rivo sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:59:39 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:59:39 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:39 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:39 rivo systemd[1]: raat-daemon.service: Succeeded. Jul 19 20:59:39 rivo systemd[1]: Stopped RAAT DAEMON. Jul 19 20:59:39 rivo volumio[3257]: info: Output device has changed, restarting Shairport Sync Jul 19 20:59:39 rivo sudo[4305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 19 20:59:39 rivo sudo[4305]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:39 rivo sudo[4305]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo sudo[4309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 19 20:59:39 rivo kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.68.58\音乐 Jul 19 20:59:39 rivo systemd[1]: Started RAAT DAEMON. Jul 19 20:59:39 rivo sudo[4297]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo sudo[4309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jul 19 20:59:39 rivo sudo[4302]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:39 rivo sudo[4298]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:39 rivo systemd[1]: mpd.service: Succeeded. Jul 19 20:59:39 rivo systemd[1]: Stopped Music Player Daemon. Jul 19 20:59:39 rivo volumio[3257]: No protocol specified Jul 19 20:59:39 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:39 rivo volumio[3257]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 19 20:59:39 rivo volumio[3257]: No protocol specified Jul 19 20:59:39 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:39 rivo volumio[3257]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:39 rivo systemd[1]: Starting Music Player Daemon... Jul 19 20:59:39 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:39 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:39 rivo volumio[3257]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:39 rivo sudo[4333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:39 rivo sudo[4333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo sudo[4337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 19 20:59:39 rivo sudo[4337]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo sudo[4333]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo sudo[4329]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 19 20:59:39 rivo sudo[4329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo sudo[4337]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo sudo[4329]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:39 rivo sudo[4345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 19 20:59:39 rivo sudo[4345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:39 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:39 rivo sudo[4345]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:39 rivo volumio[3257]: info: Executing endpoint restartRAATSocket Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 20:59:39 rivo volumio[3257]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jul 19 20:59:39 rivo volumio[3257]: info: MPD Permissions set Jul 19 20:59:39 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:39 rivo volumio[3257]: info: Raat Daemon started successfully Jul 19 20:59:39 rivo volumio[3257]: info: Cannot mount NAS Music at system boot, trial number 3 ,retrying in 5 seconds Jul 19 20:59:39 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:39 rivo sudo[4349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 19 20:59:39 rivo sudo[4349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:39 rivo volumio[3257]: info: Checking for updated MCU Firmware Jul 19 20:59:39 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:40 rivo volumio[3257]: info: Firware on device is on latest version, no need to update Jul 19 20:59:40 rivo systemd[1]: Stopping Volumio Qobuz Connect Service... Jul 19 20:59:40 rivo qobuz-connect[4276]: 20250719 20:59:40.016 [4276.4276] INFO SampleApp: Stopping Local configuration server Jul 19 20:59:40 rivo qobuz-connect[4276]: 20250719 20:59:40.027 [4276.4276] INFO SampleApp: shat down connection on UNIX socket Jul 19 20:59:40 rivo systemd[1]: qobuz-connect.service: Succeeded. Jul 19 20:59:40 rivo systemd[1]: Stopped Volumio Qobuz Connect Service. Jul 19 20:59:40 rivo volumio[3257]: info: RAAT: Requesting Headphone Status Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 20:59:40 rivo systemd[1]: Started Volumio Qobuz Connect Service. Jul 19 20:59:40 rivo sudo[4349]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: Not Reporting Auto name since its the default one Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:40 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:40 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jul 19 20:59:40 rivo volumio[3257]: info: QobuzConnect: setDeactiveState invoked Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:40 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 20:59:40 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:40 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:40 rivo volumio[3257]: info: Executing endpoint qc_getconfig Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.324 [4356.4356] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.350 [4356.4356] INFO VolumeManager: [0xabac9868]: Setting new playback volume: 75 Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.351 [4356.4356] INFO VolumeManager: [0xabac9868]: Setting new mute state: 0 Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.352 [4356.4356] INFO QobuzConnect: [0xabaca238]: Client initialized! Jul 19 20:59:40 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.353 [4356.4356] INFO SampleApp: Starting Avahi advertising, name: Rivo, service name: _qobuz-connect._tcp Jul 19 20:59:40 rivo volumio[3257]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 19 20:59:40 rivo volumio[3257]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 19 20:59:40 rivo volumio[3257]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:40 rivo volumio[3257]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.376 [4356.4356] INFO LocalConfigManager: [0xabac9148]: Starting Local Configuration server Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.377 [4356.4356] INFO SampleApp: Starting Local configuration server Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.377 [4356.4356] INFO SampleApp: Connected to UNIX socket client 0xababeed8 Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 20:59:40 rivo sudo[4374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 20:59:40 rivo sudo[4374]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:40 rivo qobuz-connect[4356]: 20250719 20:59:40.473 [4356.4356] INFO SampleApp: Playback volume changed: 75 Jul 19 20:59:40 rivo systemd[1]: Started Volumio Tidal Connect Service. Jul 19 20:59:40 rivo volumio[3257]: No protocol specified Jul 19 20:59:40 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:40 rivo sudo[4374]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:40 rivo sudo[4382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 20:59:40 rivo sudo[4382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:40 rivo volumio[3257]: info: Starting Shairport Sync Jul 19 20:59:40 rivo sudo[4382]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:40 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:40 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:40 rivo sudo[4386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 19 20:59:40 rivo sudo[4385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 20:59:40 rivo sudo[4385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:40 rivo sudo[4386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:40 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 19 20:59:40 rivo systemd[1]: Stopping RAAT DAEMON... Jul 19 20:59:40 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:40 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 20:59:40 rivo systemd[1]: raat-daemon.service: Succeeded. Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 20:59:40 rivo systemd[1]: Stopped RAAT DAEMON. Jul 19 20:59:40 rivo systemd[1]: shairport-sync.service: Succeeded. Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:40 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:40 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 20:59:40 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 19 20:59:40 rivo systemd[1]: Started RAAT DAEMON. Jul 19 20:59:40 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:40 rivo sudo[4385]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:40 rivo sudo[4386]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:41 rivo volumio[3257]: info: Raat Daemon started successfully Jul 19 20:59:41 rivo volumio[3257]: info: Shairport-Sync Started Jul 19 20:59:41 rivo volumio[3257]: info: Executing endpoint restartRAATSocket Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 20:59:41 rivo volumio[3257]: info: Executing endpoint tc_getconfig Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 19 20:59:41 rivo vtcs[4378]: STARTING TidalConnect services, version: 1.5.1-beta1 Jul 19 20:59:41 rivo vtcs[4378]: STARTED TidalConnect services. Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:41 rivo volumio[3257]: info: Executing endpoint tc_connect Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 19 20:59:41 rivo volumio[3257]: info: Connecting to TidalConnect Jul 19 20:59:41 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 19 20:59:41 rivo volumio[3257]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:41 rivo volumio[3257]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::servicePushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 20:59:41 rivo volumio[3257]: 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} Jul 19 20:59:41 rivo volumio[3257]: verbose: CURRENT POSITION 0 Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::syncState stateService stop Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::syncState currentStatus play Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::play index undefined Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo sudo[4427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 20:59:41 rivo sudo[4427]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::servicePushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: 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} Jul 19 20:59:41 rivo volumio[3257]: verbose: CURRENT POSITION 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::syncState stateService stop Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::syncState currentStatus stop Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo volumio[3257]: info: No code Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo sudo[4427]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::startPlaybackTimer Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: [1752915581516] ControllerUPNPBrowser::clearAddPlayTrack Jul 19 20:59:41 rivo volumio[3257]: verbose: ControllerMpd::sendMpdCommand stop Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::pushState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioPushState Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output update for this device Jul 19 20:59:41 rivo volumio[3257]: info: MRS: Pushing multiroomSync output Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::volumioGetQueue Jul 19 20:59:41 rivo volumio[3257]: info: CoreStateMachine::getQueue Jul 19 20:59:41 rivo volumio[3257]: info: CorePlayQueue::getQueue Jul 19 20:59:41 rivo volumio[3257]: info: [LastFM] Current track has sufficient metadata: title (So Far Away) and artist (Dire Straits) passed on explicitly Jul 19 20:59:41 rivo volumio[3257]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Jul 19 20:59:41 rivo volumio[3257]: 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: 10 Jul 19 20:59:41 rivo volumio[3257]: info: MCU Signalled Playback Inactive Jul 19 20:59:41 rivo volumio[3257]: info: RAAT: Requesting Headphone Status Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 20:59:41 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 20:59:41 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:41 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:41 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:42 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:42 rivo volumio[3257]: info: TidalConnect service stoped! Jul 19 20:59:42 rivo volumio[3257]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:42 rivo volumio[3257]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 19 20:59:43 rivo sudo[4481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 19 20:59:43 rivo sudo[4481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:43 rivo sudo[4481]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:43 rivo mpd[4348]: Jul 19 20:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 19 20:59:43 rivo systemd[1]: Started Music Player Daemon. Jul 19 20:59:43 rivo volumio[3257]: Upnp client error: Error: This socket has been ended by the other party Jul 19 20:59:43 rivo sudo[4181]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:43 rivo sudo[4082]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:43 rivo sudo[4234]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:43 rivo sudo[4309]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:43 rivo volumio[3257]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: MPD error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: The expression evaluated to a falsy value: Jul 19 20:59:43 rivo volumio[3257]: assert.ok(self.idling) Jul 19 20:59:43 rivo volumio[3257]: error: updateQueue error: null Jul 19 20:59:43 rivo volumio[3257]: 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: 11 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:59:43 rivo volumio[3257]: info: RAAT: Requesting Headphone Status Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 20:59:43 rivo volumio[3257]: info: TidalConnect service started! Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 20:59:43 rivo volumio[3257]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 19 20:59:43 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:43 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: Listing playlists Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 20:59:43 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:43 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 20:59:43 rivo volumio[3257]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 19 20:59:43 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:43 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:43 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:43 rivo volumio[3257]: info: Listing playlists Jul 19 20:59:43 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 20:59:43 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:43 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 20:59:43 rivo volumio[3257]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 20:59:43 rivo volumio[3257]: info: FusionDsp - Jul 19 20:59:44 rivo volumio[3257]: info: FusionDsp - undefined Jul 19 20:59:44 rivo volumio[3257]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 20:59:44 rivo volumio[3257]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 20:59:44 rivo volumio[3257]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 20:59:44 rivo volumio[3257]: info: FusionDsp - Jul 19 20:59:44 rivo volumio[3257]: info: FusionDsp - Jul 19 20:59:44 rivo volumio[3257]: info: FusionDsp - Jul 19 20:59:45 rivo volumio[3257]: info: FusionDsp - undefined Jul 19 20:59:45 rivo volumio[3257]: info: FusionDsp - undefined Jul 19 20:59:45 rivo sudo[4504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.68.58/音乐 /mnt/NAS/Music Jul 19 20:59:45 rivo sudo[4504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:45 rivo volumio[3257]: info: FusionDsp - undefined Jul 19 20:59:45 rivo kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.68.58\音乐 Jul 19 20:59:45 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jul 19 20:59:45 rivo sudo[4504]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:45 rivo volumio[3257]: info: TidalConnect service started! Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 19 20:59:45 rivo volumio[3257]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 20:59:45 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:45 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:45 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:45 rivo volumio[3257]: info: Executing endpoint metavolumio Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 20:59:45 rivo volumio[3257]: info: Executing endpoint metavolumio Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 20:59:45 rivo volumio[3257]: info: Executing endpoint metavolumio Jul 19 20:59:45 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 19 20:59:46 rivo volumio[3257]: info: TidalConnect service started! Jul 19 20:59:46 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 19 20:59:46 rivo volumio[3257]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 19 20:59:48 rivo volumio[3257]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 19 20:59:48 rivo volumio[3257]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 19 20:59:48 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:48 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:48 rivo volumio[3257]: info: Starting MyVolumio Remote Streaming Endpoints Jul 19 20:59:48 rivo volumio[3257]: info: MyVolumio login type: Token Jul 19 20:59:48 rivo volumio[3257]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 19 20:59:48 rivo volumio[3257]: 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' Jul 19 20:59:50 rivo volumio[3257]: error: MyVolumio Custom Token format not valid, refreshing it Jul 19 20:59:50 rivo sudo[4513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=guest,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.68.58/音乐 /mnt/NAS/Music Jul 19 20:59:50 rivo sudo[4513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:50 rivo kernel: CIFS VFS: BAD_NETWORK_NAME: \\192.168.68.58\音乐 Jul 19 20:59:50 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -2 Jul 19 20:59:50 rivo sudo[4513]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:50 rivo volumio[3257]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 19 20:59:51 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:53 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:53 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:53 rivo volumio[3257]: verbose: New Socket.io Connection to 192.168.68.61:3000 from 192.168.68.52 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 12 Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:53 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 19 20:59:53 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 19 20:59:54 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:54 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:55 rivo sudo[4537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:59:55 rivo sudo[4537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:55 rivo sudo[4537]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:55 rivo sudo[4540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:59:55 rivo sudo[4540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:55 rivo sudo[4540]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:55 rivo volumio[3257]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.52 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Jul 19 20:59:56 rivo sudo[4545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 19 20:59:56 rivo sudo[4545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:56 rivo sudo[4545]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:56 rivo volumio[3257]: info: MyVolumio login type: Token Jul 19 20:59:56 rivo sudo[4549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 19 20:59:56 rivo sudo[4549]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 20:59:56 rivo sudo[4549]: pam_unix(sudo:session): session closed for user root Jul 19 20:59:56 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:56 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:56 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:56 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:56 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:56 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:56 rivo volumio[3257]: verbose: New Socket.io Connection to 192.168.68.61 from 192.168.68.52 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::volumioGetVisibleSources Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:57 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 19 20:59:57 rivo volumio[3257]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 19 20:59:57 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:57 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:57 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:57 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:57 rivo volumio[3257]: info: Listing playlists Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 19 20:59:57 rivo volumio[3257]: info: FusionDsp - Jul 19 20:59:57 rivo volumio[3257]: info: FusionDsp - undefined Jul 19 20:59:57 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 19 20:59:58 rivo volumio[3257]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 19 20:59:58 rivo volumio[3257]: error: MyVolumio Plugin failed to start in a timely fashion Jul 19 20:59:58 rivo volumio[3257]: [Metrics] CommandRouter: 57s 367.92ms Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::volumiosetStartupVolume Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::Close All Modals sent Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::Close All Modals sent Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 19 20:59:58 rivo volumio[3257]: info: camilladsp stopping service pid 4021... Jul 19 20:59:58 rivo volumio[3257]: info: camilladsp service terminated, instance 1 Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 20:59:58 rivo volumio[3257]: info: camilladsp service started and running in background, instance 1 Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 19 20:59:58 rivo volumio[3257]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 19 20:59:58 rivo volumio[3257]: error: FusionDsp - WebSocket error: [object Object] Jul 19 20:59:58 rivo volumio[3257]: info: camilladsp spawned new process with pid 4557, instance 1, run: true Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 20:59:58 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:58 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:58 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 20:59:58 rivo volumio[3257]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 19 20:59:58 rivo volumio[3257]: info: Received Get System Version Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:58 rivo volumio[3257]: info: Received Get System Info Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 20:59:58 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 20:59:58 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 20:59:58 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 19 20:59:59 rivo volumio[3257]: No protocol specified Jul 19 20:59:59 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 19 20:59:59 rivo volumio[3257]: info: MyVolumio token set successfully Jul 19 20:59:59 rivo volumio[3257]: info: MYVOLUMIO: Adding device Jul 19 20:59:59 rivo volumio[3257]: info: MYVOLUMIO: Evaluating Server Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 20:59:59 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 19 20:59:59 rivo volumio[3257]: info: MyVolumio status changed Jul 19 20:59:59 rivo volumio[3257]: info: Streaming services startup Jul 19 20:59:59 rivo volumio[3257]: info: Starting Streaming Daemon Jul 19 21:00:00 rivo volumio[3257]: info: Removing browser output: myVolumio user plan is not superstar Jul 19 21:00:00 rivo volumio[3257]: info: Removing audio output: Jul 19 21:00:00 rivo volumio[3257]: info: Stoppping Tunnel 1 Jul 19 21:00:00 rivo sudo[4586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 19 21:00:00 rivo sudo[4586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 21:00:00 rivo sudo[4586]: pam_unix(sudo:session): session closed for user root Jul 19 21:00:00 rivo volumio-remote-updater[2830]: No test mode Jul 19 21:00:00 rivo volumio-remote-updater[2830]: No alpha test mode Jul 19 21:00:00 rivo sudo[4589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 19 21:00:00 rivo sudo[4589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 21:00:00 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 19 21:00:00 rivo volumio[3257]: error: Cannot start Volumio Streaming Daemon Jul 19 21:00:00 rivo volumio[3257]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 19 21:00:00 rivo volumio[3257]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 19 21:00:00 rivo sudo[4589]: pam_unix(sudo:session): session closed for user root Jul 19 21:00:00 rivo volumio[3257]: info: Remote SSH Stopped Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 21:00:01 rivo volumio[3257]: info: Received Get System Info Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 21:00:01 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 21:00:01 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 21:00:01 rivo volumio[3257]: info: Received Get System Info Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 21:00:01 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 21:00:01 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 21:00:01 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 21:00:02 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 19 21:00:02 rivo volumio[3257]: info: Received Get System Info Jul 19 21:00:02 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 21:00:02 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 21:00:02 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 21:00:02 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 21:00:02 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 21:00:02 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 21:00:03 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 21:00:03 rivo volumio[3257]: info: camilladsp spawned new process with pid 4593, instance 1, run: true Jul 19 21:00:03 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 19 21:00:03 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 21:00:03 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 21:00:04 rivo volumio[3257]: info: CoreCommandRouter::volumioGetQueue Jul 19 21:00:04 rivo volumio[3257]: info: CoreStateMachine::getQueue Jul 19 21:00:04 rivo volumio[3257]: info: CorePlayQueue::getQueue Jul 19 21:00:05 rivo volumio[3257]: info: BOOT COMPLETED Jul 19 21:00:05 rivo volumio[3257]: info: Setting Geolocation for MyVolumio to au1 Jul 19 21:00:05 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:05 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:05 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:05 rivo volumio[3257]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 19 21:00:05 rivo volumio[3257]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 19 21:00:05 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 19 21:00:06 rivo volumio[3257]: info: Updating MyVolumio device info Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 19 21:00:06 rivo volumio[3257]: info: Not Reporting Auto name since its the default one Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jul 19 21:00:06 rivo volumio[3257]: No protocol specified Jul 19 21:00:06 rivo volumio[3257]: xcb_connection_has_error() returned true Jul 19 21:00:06 rivo volumio[3257]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 19 21:00:06 rivo sudo[4626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jul 19 21:00:06 rivo sudo[4626]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 21:00:06 rivo sudo[4626]: pam_unix(sudo:session): session closed for user root Jul 19 21:00:06 rivo sudo[4628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jul 19 21:00:06 rivo sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 19 21:00:06 rivo systemd[1]: Stopping RAAT DAEMON... Jul 19 21:00:06 rivo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jul 19 21:00:06 rivo systemd[1]: raat-daemon.service: Succeeded. Jul 19 21:00:06 rivo systemd[1]: Stopped RAAT DAEMON. Jul 19 21:00:06 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 19 21:00:06 rivo volumio[3257]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 19 21:00:06 rivo systemd[1]: Started RAAT DAEMON. Jul 19 21:00:06 rivo sudo[4628]: pam_unix(sudo:session): session closed for user root Jul 19 21:00:06 rivo volumio[3257]: info: Raat Daemon started successfully Jul 19 21:00:06 rivo volumio[3257]: info: Executing endpoint restartRAATSocket Jul 19 21:00:06 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jul 19 21:00:06 rivo volumio[3257]: info: CorePlayQueue::getTrack 1 Jul 19 21:00:06 rivo volumio[3257]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::volumioGetBrowseSources Jul 19 21:00:08 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 21:00:09 rivo volumio[3257]: info: RAAT: Requesting Headphone Status Jul 19 21:00:09 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jul 19 21:00:09 rivo volumio[3257]: info: MYVOLUMIO: Adding device Jul 19 21:00:09 rivo volumio[3257]: info: MYVOLUMIO: Evaluating Server Jul 19 21:00:09 rivo volumio[3257]: info: MCU Signalled Headphone Mode Disabled Jul 19 21:00:09 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 19 21:00:09 rivo volumio[3257]: info: Reporting Headphone State: false Jul 19 21:00:09 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 21:00:09 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 19 21:00:09 rivo volumio[3257]: info: Updating RAAT Signal Path Jul 19 21:00:11 rivo volumio[3257]: info: Preload queue cleared Jul 19 21:00:11 rivo volumio[3257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::ClearQueue Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::stop Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::clearPlayQueue Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::saveQueue Jul 19 21:00:11 rivo volumio[3257]: info: CoreCommandRouter::volumioPushQueue Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::addQueueItems Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::addQueueItems Jul 19 21:00:11 rivo volumio[3257]: info: Preload queue cleared Jul 19 21:00:11 rivo volumio[3257]: info: Adding Item to queue: upnp/folder/http://192.168.68.58:52100/ContentDirectory/control@2307 Jul 19 21:00:11 rivo volumio[3257]: info: Exploding uri upnp/folder/http://192.168.68.58:52100/ContentDirectory/control@2307 in service upnp_browser Jul 19 21:00:11 rivo volumio[3257]: info: CoreCommandRouter::volumioPushQueue Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::saveQueue Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::updateTrackBlock Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::getTrackBlock Jul 19 21:00:11 rivo volumio[3257]: info: CoreCommandRouter::volumioPlay Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::play index 0 Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::stop Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::play index undefined Jul 19 21:00:11 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:11 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 21:00:12 rivo volumio[3257]: info: Preload queue cleared Jul 19 21:00:12 rivo volumio[3257]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::ClearQueue Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::stop Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::clearPlayQueue Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::saveQueue Jul 19 21:00:12 rivo volumio[3257]: info: CoreCommandRouter::volumioPushQueue Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::addQueueItems Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::addQueueItems Jul 19 21:00:12 rivo volumio[3257]: info: Preload queue cleared Jul 19 21:00:12 rivo volumio[3257]: info: Adding Item to queue: upnp/folder/http://192.168.68.58:52100/ContentDirectory/control@2307 Jul 19 21:00:12 rivo volumio[3257]: info: Using cached record of: upnp/folder/http://192.168.68.58:52100/ContentDirectory/control@2307 Jul 19 21:00:12 rivo volumio[3257]: info: CoreCommandRouter::volumioPushQueue Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::saveQueue Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::updateTrackBlock Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::getTrackBlock Jul 19 21:00:12 rivo volumio[3257]: info: CoreCommandRouter::volumioPlay Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::play index 0 Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::stop Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::play index undefined Jul 19 21:00:12 rivo volumio[3257]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 19 21:00:12 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 21:00:15 rivo volumio[3257]: info: Setting Geolocation for MyVolumio to au1 Jul 19 21:00:15 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 19 21:00:16 rivo volumio[3257]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 19 21:00:16 rivo volumio[3257]: info: Received Get System Version Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 19 21:00:16 rivo volumio[3257]: info: Received Get System Info Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 19 21:00:16 rivo volumio[3257]: info: Discovery: Getting this device information Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::volumioGetState Jul 19 21:00:16 rivo volumio[3257]: info: CorePlayQueue::getTrack 0 Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 19 21:00:16 rivo volumio[3257]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 19 21:00:16 rivo volumio[3257]: info: Updating MyVolumio device info Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 19 21:00:16 rivo volumio[3257]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 19 21:00:17 rivo volumio[3257]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 21:00:17 rivo volumio[3257]: TypeError: Cannot read property 'length' of undefined Jul 19 21:00:17 rivo volumio[3257]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 19 21:00:17 rivo volumio[3257]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 19 21:00:17 rivo volumio[3257]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 19 21:00:17 rivo volumio[3257]: at Parser.emit (events.js:400:28) Jul 19 21:00:17 rivo volumio[3257]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 19 21:00:17 rivo volumio[3257]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 19 21:00:17 rivo volumio[3257]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 19 21:00:17 rivo volumio[3257]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 19 21:00:17 rivo volumio[3257]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 19 21:00:17 rivo volumio[3257]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 19 21:00:17 rivo volumio[3257]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 19 21:00:17 rivo volumio[3257]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 19 21:00:17 rivo volumio[3257]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 19 21:00:17 rivo volumio[3257]: at IncomingMessage.emit (events.js:412:35) Jul 19 21:00:17 rivo volumio[3257]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 19 21:00:17 rivo volumio[3257]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 19 21:00:17 rivo volumio[3257]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 19 21:00:17 rivo sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-19 20:59 Jul 19 21:00:17 rivo sudo[4724]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 05:15:38 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="13b9c0f9785de91e97db9573691d3efe"