-- Logs begin at Mon 2025-06-02 10:53:17 +08, end at Mon 2025-07-07 08:59:00 +08. -- Jul 07 08:58:21 primo07 systemd-timedated[3422]: Changed local time to Mon Jul 7 08:58:21 2025 Jul 07 08:58:21 primo07 systemd[1]: Starting Daily apt download activities... Jul 07 08:58:21 primo07 volumio-time-update[2814]: volumio-time-update-util: System time updated successfully. Jul 07 08:58:21 primo07 sudo[3420]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:21 primo07 systemd[1]: Starting Daily man-db regeneration... Jul 07 08:58:21 primo07 systemd[1]: Started Volumio Time Update Utility. Jul 07 08:58:21 primo07 systemd[1]: Reached target Multi-User System. Jul 07 08:58:21 primo07 systemd[1]: Reached target Graphical Interface. Jul 07 08:58:21 primo07 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 07 08:58:21 primo07 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 07 08:58:21 primo07 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 07 08:58:21 primo07 systemd[1]: man-db.service: Succeeded. Jul 07 08:58:21 primo07 systemd[1]: Started Daily man-db regeneration. Jul 07 08:58:21 primo07 ntpd[3310]: Soliciting pool server 38.60.193.17 Jul 07 08:58:21 primo07 ntpd[3310]: Soliciting pool server 111.90.158.16 Jul 07 08:58:21 primo07 volumio[3283]: info: MYVOLUMIO Environment detected Jul 07 08:58:22 primo07 volumio[3283]: info: Plugin folders cleanup Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning into folder /volumio/app/plugins/ Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category audio_interface Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category miscellanea Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category music_service Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category plugins.json Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category system_controller Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category user_interface Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning into folder /data/plugins/ Jul 07 08:58:22 primo07 volumio[3283]: info: Scanning category music_service Jul 07 08:58:22 primo07 volumio[3283]: info: Plugin folders cleanup completed Jul 07 08:58:22 primo07 volumio[3283]: info: ------------------------------------------- Jul 07 08:58:22 primo07 volumio[3283]: info: ----- Core plugins startup ---- Jul 07 08:58:22 primo07 volumio[3283]: info: ------------------------------------------- Jul 07 08:58:22 primo07 volumio[3283]: info: Loading plugins from folder /volumio/app/plugins/ Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin bluetooth to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin multiroom to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin upnp to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin metavolumio to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin cd_controller to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin raat to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 07 08:58:22 primo07 volumio[3283]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 07 08:58:22 primo07 systemd[1]: apt-daily.service: Succeeded. Jul 07 08:58:22 primo07 volumio[3283]: info: Loading plugins from folder /data/plugins/ Jul 07 08:58:22 primo07 systemd[1]: Started Daily apt download activities. Jul 07 08:58:22 primo07 volumio[3283]: info: Loading plugin "system"... Jul 07 08:58:22 primo07 systemd[1]: Starting Daily apt upgrade and clean activities... Jul 07 08:58:22 primo07 volumio[3283]: info: Loading plugin "appearance"... Jul 07 08:58:22 primo07 ntpd[3310]: Soliciting pool server 111.90.158.16 Jul 07 08:58:22 primo07 systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 07 08:58:22 primo07 systemd[1]: Started Daily apt upgrade and clean activities. Jul 07 08:58:22 primo07 systemd[1]: Startup finished in 12.923s (kernel) + 27.129s (userspace) = 40.052s. Jul 07 08:58:23 primo07 volumio[3283]: info: Loading plugin "network"... Jul 07 08:58:23 primo07 volumio[3283]: info: Refreshing Cached IP Addresses Jul 07 08:58:23 primo07 sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 08:58:23 primo07 sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:23 primo07 sudo[3539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 08:58:23 primo07 sudo[3539]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:23 primo07 sudo[3537]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:23 primo07 sudo[3539]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:23 primo07 volumio[3283]: info: Loading plugin "services"... Jul 07 08:58:23 primo07 volumio[3283]: info: Loading plugin "alsa_controller"... Jul 07 08:58:24 primo07 sudo[3552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 07 08:58:24 primo07 sudo[3552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:24 primo07 kernel: wl_escan_set_scan: ioctl_ver=2 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "wizard"... Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "networkfs"... Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio[3283]: info: Starting Udev Watcher for removable devices Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio[3283]: info: Ignoring mount for partition: BOOT Jul 07 08:58:24 primo07 volumio[3283]: info: Ignoring mount for partition: volumio Jul 07 08:58:24 primo07 volumio[3283]: info: Ignoring mount for partition: volumio_data Jul 07 08:58:24 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "volumio_command_line_client"... Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "upnp"... Jul 07 08:58:24 primo07 volumio[3283]: info: [1751849904339] Starting Upmpd Daemon Jul 07 08:58:24 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "my_music"... Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "mpd"... Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio-remote-updater[2815]: [2025-07-07 08:58:24] [connect] Successful connection Jul 07 08:58:24 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:24 primo07 volumio[3283]: info: Loading plugin "upnp_browser"... Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 volumio[3283]: info: Loading plugin "bluetooth"... Jul 07 08:58:25 primo07 volumio[3283]: info: [1751849905718] Starting BluetoothController Jul 07 08:58:25 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:25 primo07 volumio[3283]: info: Loading plugin "alarm-clock"... Jul 07 08:58:26 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:26 primo07 volumio[3283]: info: Loading plugin "manifestui"... Jul 07 08:58:26 primo07 volumio[3283]: info: Loading plugin "metavolumio"... Jul 07 08:58:26 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:26 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:27 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:28 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:28 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 8 Jul 07 08:58:29 primo07 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc03ce5a000 drv_status 0x0 e_type 69 e_states 0 Jul 07 08:58:29 primo07 sudo[3552]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:29 primo07 volumio[3283]: info: Loading plugin "airplay_emulation"... Jul 07 08:58:29 primo07 volumio[3283]: info: Starting Shairport Sync Jul 07 08:58:29 primo07 volumio[3283]: info: Loading plugin "cd_controller"... Jul 07 08:58:29 primo07 volumio[3283]: info: Loading plugin "last_100"... Jul 07 08:58:29 primo07 volumio[3283]: info: Loading plugin "qobuzconnect"... Jul 07 08:58:29 primo07 volumio[3283]: info: Loading plugin "raat"... Jul 07 08:58:29 primo07 systemd[1]: systemd-fsckd.service: Succeeded. Jul 07 08:58:30 primo07 volumio[3283]: info: RAAT Plugin loaded Jul 07 08:58:30 primo07 volumio[3283]: info: Adding restartRAATSocket REST API Endpoint Jul 07 08:58:30 primo07 volumio[3283]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Jul 07 08:58:30 primo07 volumio[3283]: info: Loading plugin "streaming_services"... Jul 07 08:58:31 primo07 sh[2750]: timed out Jul 07 08:58:31 primo07 sh[2750]: dhcpcd exited Jul 07 08:58:31 primo07 dhcpcd[2800]: timed out Jul 07 08:58:31 primo07 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 07 08:58:31 primo07 sh[2750]: ifup: failed to bring up eth0 Jul 07 08:58:31 primo07 dhcpcd[2800]: dhcpcd exited Jul 07 08:58:31 primo07 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 07 08:58:32 primo07 volumio[3283]: info: Starting Streaming Service Transparent Proxy Jul 07 08:58:32 primo07 volumio[3283]: info: Loading plugin "tidalconnect"... Jul 07 08:58:32 primo07 volumio[3283]: info: Loading plugin "webradio"... Jul 07 08:58:32 primo07 volumio[3283]: info: Loading plugin "i2s_dacs"... Jul 07 08:58:32 primo07 volumio[3283]: info: I2S DAC not set, start Auto-detection Jul 07 08:58:32 primo07 volumio[3283]: info: Loading plugin "volumiodiscovery"... Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** For more information see Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** For more information see Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 07 08:58:33 primo07 volumio[3283]: *** WARNING *** For more information see Jul 07 08:58:33 primo07 node[3283]: *** WARNING *** For more information see Jul 07 08:58:33 primo07 volumio[3283]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 07 08:58:33 primo07 volumio[3283]: info: Discovery: Started advertising with name: Primo07 Jul 07 08:58:33 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 08:58:33 primo07 volumio[3283]: info: Loading plugin "spop"... Jul 07 08:58:34 primo07 volumio[3283]: STREAMING PROXY: Starting server on port 3245 Jul 07 08:58:34 primo07 volumio[3283]: Node JS runtime: 14 Jul 07 08:58:35 primo07 volumio[3283]: info: Loading plugin "multiroom"... Jul 07 08:58:37 primo07 volumio[3283]: info: Applying required configuration parameters for plugin multiroom Jul 07 08:58:37 primo07 sudo[3756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 07 08:58:37 primo07 sudo[3756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:37 primo07 sudo[3756]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:37 primo07 volumio[3283]: info: MRS: MultiRoom plugin initialized Jul 07 08:58:37 primo07 volumio[3283]: info: MRS: STOPPING SNAPCLIENT Jul 07 08:58:37 primo07 volumio[3283]: info: MRS: Snap server stop Jul 07 08:58:37 primo07 sudo[3772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 07 08:58:37 primo07 volumio[3283]: info: MRS: STOPPING volumioStreaming Jul 07 08:58:37 primo07 sudo[3772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:37 primo07 sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 07 08:58:37 primo07 sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:37 primo07 sudo[3772]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:37 primo07 volumio[3283]: info: Loading plugin "outputs"... Jul 07 08:58:37 primo07 sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 07 08:58:37 primo07 sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:37 primo07 volumio[3283]: info: Loading plugin "albumart"... Jul 07 08:58:37 primo07 sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 07 08:58:37 primo07 sudo[3774]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:37 primo07 sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:37 primo07 sudo[3780]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:37 primo07 sudo[3777]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:37 primo07 volumio[3283]: info: Plugin example_plugin is not enabled Jul 07 08:58:37 primo07 volumio[3283]: info: Loading plugin "hi_res_audio"... Jul 07 08:58:38 primo07 volumio[3283]: Forking 3 albumart workers Jul 07 08:58:39 primo07 volumio[3283]: Starting albumart workers Jul 07 08:58:39 primo07 volumio[3283]: Starting albumart workers Jul 07 08:58:39 primo07 volumio[3283]: Starting albumart workers Jul 07 08:58:40 primo07 volumio[3283]: info: Applying required configuration parameters for plugin hi_res_audio Jul 07 08:58:40 primo07 volumio[3283]: info: Loading plugin "inputs"... Jul 07 08:58:40 primo07 volumio-remote-updater[2815]: [2025-07-07 08:58:40] [connect] Successful connection Jul 07 08:58:40 primo07 volumio[3283]: info: Loading plugin "qobuz"... Jul 07 08:58:42 primo07 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 07 08:58:42 primo07 volumio[3283]: info: Loading plugin "smart_inputs"... Jul 07 08:58:43 primo07 volumio[3283]: info: Loading plugin "tidal"... Jul 07 08:58:45 primo07 volumio[3283]: info: Loading plugin "primo2rev2control"... Jul 07 08:58:45 primo07 volumio[3283]: info: Adding this device properties Jul 07 08:58:45 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Jul 07 08:58:45 primo07 volumio[3283]: info: Setting Additional Device Volumio Properties: [object Object] Jul 07 08:58:45 primo07 volumio[3283]: info: Loading plugin "updater_comm"... Jul 07 08:58:45 primo07 volumio[3283]: info: Loading plugin "cec_controller"... Jul 07 08:58:45 primo07 kernel: fb: mem_free_work, free memory: addr:800000 Jul 07 08:58:45 primo07 volumio[3283]: info: Applying required configuration parameters for plugin cec_controller Jul 07 08:58:45 primo07 volumio[3283]: info: Plugin mpdemulation is not enabled Jul 07 08:58:45 primo07 volumio[3283]: info: Loading plugin "rest_api"... Jul 07 08:58:45 primo07 volumio[3283]: info: Loading plugin "websocket"... Jul 07 08:58:45 primo07 volumio[3283]: info: Starting Socket.io Server version 2.3.0 Jul 07 08:58:45 primo07 volumio[3283]: info: Loading i18n strings for locale en Jul 07 08:58:45 primo07 volumio[3283]: Updating browse sources language Jul 07 08:58:45 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::initPlayerControls Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 08:58:46 primo07 volumio[3283]: Express server listening on port 3000 Jul 07 08:58:46 primo07 volumio[3283]: [Metrics] WebUI: 26s 479.98ms Jul 07 08:58:46 primo07 volumio[3283]: info: CoreStateMachine::resetVolumioState Jul 07 08:58:46 primo07 volumio[3283]: info: CoreStateMachine::getcurrentVolume Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:46 primo07 sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 07 08:58:46 primo07 sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:46 primo07 sudo[3874]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:46 primo07 volumio[3283]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 07 08:58:46 primo07 sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 07 08:58:46 primo07 volumio[3283]: info: Completed loading Core Plugins Jul 07 08:58:46 primo07 sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:46 primo07 volumio[3283]: info: Preparing to generate the ALSA configuration file Jul 07 08:58:46 primo07 sudo[3877]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:46 primo07 volumio[3283]: info: Volumio Network Manager: Network status updated: 2 Jul 07 08:58:46 primo07 volumio[3283]: Unable to parse: Jul 07 08:58:46 primo07 volumio[3283]: Simple mixer control 'Audio hdmi-out mute',0 Jul 07 08:58:46 primo07 volumio[3283]: Capabilities: pswitch pswitch-joined Jul 07 08:58:46 primo07 volumio[3283]: Playback channels: Mono Jul 07 08:58:46 primo07 volumio[3283]: Mono: Playback [on] Jul 07 08:58:46 primo07 volumio[3283]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 07 08:58:46 primo07 volumio[3283]: info: MRS: Removed streaming files Jul 07 08:58:46 primo07 volumio[3283]: info: MRS: volumioStreaming STOPPED Jul 07 08:58:46 primo07 volumio[3283]: info: MRS: SNAPSERVER STOPPED Jul 07 08:58:46 primo07 volumio[3283]: info: MRS: SNAPCLIENT STOPPED Jul 07 08:58:46 primo07 volumio[3283]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 07 08:58:46 primo07 volumio[3283]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 07 08:58:46 primo07 volumio[3283]: info: Reading ALSA contributions from plugins. Jul 07 08:58:46 primo07 volumio[3283]: Unable to parse: Jul 07 08:58:46 primo07 volumio[3283]: Simple mixer control 'Audio hdmi-out mute',0 Jul 07 08:58:46 primo07 volumio[3283]: Capabilities: pswitch pswitch-joined Jul 07 08:58:46 primo07 volumio[3283]: Playback channels: Mono Jul 07 08:58:46 primo07 volumio[3283]: Mono: Playback [on] Jul 07 08:58:46 primo07 volumio[3283]: info: VolumeController:: Volume=undefined Mute =false Jul 07 08:58:46 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:46 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:46 primo07 volumio[3283]: info: CoreStateMachine::updateTrackBlock Jul 07 08:58:46 primo07 volumio[3283]: info: CorePlayQueue::getTrackBlock Jul 07 08:58:46 primo07 volumio[3283]: info: CoreCommandRouter::volumioRetrievevolume Jul 07 08:58:47 primo07 volumio[3283]: info: Reloading queue from file Jul 07 08:58:47 primo07 volumio[3283]: info: CoreStateMachine::setRepeat false single undefined Jul 07 08:58:47 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:47 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:47 primo07 volumio[3283]: info: CoreStateMachine::setRandom false Jul 07 08:58:47 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:47 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:47 primo07 volumio[3283]: Unable to parse: Jul 07 08:58:47 primo07 volumio[3283]: Simple mixer control 'Audio hdmi-out mute',0 Jul 07 08:58:47 primo07 volumio[3283]: Capabilities: pswitch pswitch-joined Jul 07 08:58:47 primo07 volumio[3283]: Playback channels: Mono Jul 07 08:58:47 primo07 volumio[3283]: Mono: Playback [on] Jul 07 08:58:47 primo07 volumio[3283]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 07 08:58:47 primo07 sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 07 08:58:47 primo07 sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 volumio[3283]: info: Discovery: adding 6f8343da-8081-40a0-9a3f-b509974c262a Jul 07 08:58:47 primo07 volumio[3283]: info: Discovery: Found device Primo07 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:47 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:47 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output for this device Jul 07 08:58:47 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:47 primo07 volumio[3283]: info: Adding audio output: Jul 07 08:58:47 primo07 volumio[3283]: info: Adding audio output: Jul 07 08:58:47 primo07 volumio[3283]: Unable to parse: Jul 07 08:58:47 primo07 volumio[3283]: Simple mixer control 'Audio hdmi-out mute',0 Jul 07 08:58:47 primo07 volumio[3283]: Capabilities: pswitch pswitch-joined Jul 07 08:58:47 primo07 volumio[3283]: Playback channels: Mono Jul 07 08:58:47 primo07 volumio[3283]: Mono: Playback [on] Jul 07 08:58:47 primo07 volumio[3283]: info: VolumeController:: Volume=undefined Mute =false Jul 07 08:58:47 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:47 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:47 primo07 volumio[3283]: info: Asound.conf file unchanged, so no further update is needed Jul 07 08:58:47 primo07 volumio[3283]: info: Output device has changed, restarting MPD Jul 07 08:58:47 primo07 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 07 08:58:47 primo07 sudo[3891]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:47 primo07 sudo[3898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 08:58:47 primo07 sudo[3898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 sudo[3898]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:47 primo07 volumio[3283]: info: Output device has changed, restarting Shairport Sync Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:47 primo07 sudo[3906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 08:58:47 primo07 sudo[3906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 systemd[1]: Stopping Music Player Daemon... Jul 07 08:58:47 primo07 volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 08:58:47 primo07 volumio[3283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: ___________ START PLUGINS ___________ Jul 07 08:58:47 primo07 volumio[3283]: info: ControllerMpd::onStart: Initializing MPD Jul 07 08:58:47 primo07 volumio[3283]: info: Creating MPD Configuration file Jul 07 08:58:47 primo07 sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 07 08:58:47 primo07 sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 kernel: aml_tdm_open Jul 07 08:58:47 primo07 kernel: Not init audio effects Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 08:58:47 primo07 volumio[3283]: info: [1751849927637] CoreMusicLibrary::Adding element Media Servers Jul 07 08:58:47 primo07 sudo[3914]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:47 primo07 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 07 08:58:47 primo07 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 07 08:58:47 primo07 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 07 08:58:47 primo07 systemd[1]: mpd.service: Succeeded. Jul 07 08:58:47 primo07 systemd[1]: Stopped Music Player Daemon. Jul 07 08:58:47 primo07 systemd[1]: Starting Music Player Daemon... Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 07 08:58:47 primo07 sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 07 08:58:47 primo07 sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 volumio[3283]: info: Adding METAVOLUMIO REST API Endpoints Jul 07 08:58:47 primo07 volumio[3283]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 07 08:58:47 primo07 volumio[3283]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 07 08:58:47 primo07 volumio[3283]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 07 08:58:47 primo07 volumio[3283]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:47 primo07 sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Primo07 Jul 07 08:58:47 primo07 sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 07 08:58:47 primo07 systemd[1]: mpd.service: Succeeded. Jul 07 08:58:47 primo07 sudo[3922]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:47 primo07 systemd[1]: Stopped Music Player Daemon. Jul 07 08:58:47 primo07 systemd[1]: Starting Music Player Daemon... Jul 07 08:58:47 primo07 volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:47 primo07 volumio[3283]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 07 08:58:47 primo07 volumio[3283]: info: Preparing CD Folders Jul 07 08:58:47 primo07 volumio[3283]: info: Adding CD REST API Endpoints Jul 07 08:58:47 primo07 volumio[3283]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 07 08:58:47 primo07 volumio[3283]: info: Starting UDEV Watcher for CD Jul 07 08:58:47 primo07 volumio[3283]: info: Detecting CD presence with UDEV Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 07 08:58:47 primo07 sudo[3927]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 07 08:58:47 primo07 sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 08:58:47 primo07 volumio[3283]: info: [1751849927931] CoreMusicLibrary::Adding element Last_100 Jul 07 08:58:47 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:47 primo07 volumio[3283]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jul 07 08:58:47 primo07 sudo[3927]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:47 primo07 volumio[3283]: info: QobuzConnect: Starting Qobuz Connect socket and service Jul 07 08:58:48 primo07 volumio[3283]: info: Starting RAAT Plugin Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 07 08:58:48 primo07 sudo[3937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jul 07 08:58:48 primo07 volumio[3283]: info: Additional UI Settings Added for plugin music_service/raat Jul 07 08:58:48 primo07 volumio[3283]: info: Registering DSP Elements listener and retrieving current ones Jul 07 08:58:48 primo07 sudo[3937]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 volumio[3283]: info: Additional DSP elements updated Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:48 primo07 volumio[3283]: info: Updating RAAT Signal Path Jul 07 08:58:48 primo07 volumio[3283]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 07 08:58:48 primo07 volumio[3283]: info: Streaming services startup Jul 07 08:58:48 primo07 volumio[3283]: info: Starting Streaming Daemon Jul 07 08:58:48 primo07 sudo[3937]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:48 primo07 sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jul 07 08:58:48 primo07 sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 07 08:58:48 primo07 sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 systemd[1]: Started Volumio Qobuz Connect Service. Jul 07 08:58:48 primo07 sudo[3942]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:48 primo07 sudo[3943]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 08:58:48 primo07 volumio[3283]: info: [1751849928297] CoreMusicLibrary::Adding element Webradio Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 08:58:48 primo07 volumio[3283]: info: Initializing BBC Radios Jul 07 08:58:48 primo07 sudo[3954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 07 08:58:48 primo07 sudo[3954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 sudo[3954]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:48 primo07 volumio[3283]: info: Creating Spotify config file Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 07 08:58:48 primo07 volumio[3283]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 07 08:58:48 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:48 primo07 volumio[3283]: error: Hi Res Audio Failed Login: Missing Login Data Jul 07 08:58:48 primo07 volumio[3283]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 07 08:58:48 primo07 volumio[3283]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 07 08:58:48 primo07 volumio[3283]: info: Initializing Serial Communication on port /dev/ttyS3 Jul 07 08:58:48 primo07 volumio[3283]: info: Touch Event Listener Process Starting Jul 07 08:58:48 primo07 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Jul 07 08:58:48 primo07 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Jul 07 08:58:48 primo07 kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Jul 07 08:58:48 primo07 kernel: aml_tdm_open Jul 07 08:58:48 primo07 kernel: Not init audio effects Jul 07 08:58:48 primo07 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 07 08:58:48 primo07 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 07 08:58:48 primo07 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 07 08:58:48 primo07 volumio[3283]: info: Adding inputs REST Endpoints Jul 07 08:58:48 primo07 volumio[3283]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 07 08:58:48 primo07 volumio[3283]: info: Scanning Audio Inputs Jul 07 08:58:48 primo07 sudo[3973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Jul 07 08:58:48 primo07 sudo[3973]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 volumio[3283]: info: Checking against Known Cards name Jul 07 08:58:48 primo07 volumio[3283]: info: Checking against Known Cards name Jul 07 08:58:48 primo07 volumio[3283]: info: Checking against Known Cards name Jul 07 08:58:48 primo07 volumio[3283]: info: Checking against Known Cards name Jul 07 08:58:48 primo07 volumio[3283]: info: Checking against Known Cards name Jul 07 08:58:48 primo07 volumio[3283]: info: Adding Server instance for streaming Jul 07 08:58:48 primo07 volumio[3283]: info: Refreshing TIDAL token Jul 07 08:58:48 primo07 sudo[3981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Jul 07 08:58:48 primo07 sudo[3981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:48 primo07 sudo[3981]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:49 primo07 sudo[3984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Jul 07 08:58:49 primo07 sudo[3984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:49 primo07 sudo[3984]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:49 primo07 sudo[3987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Jul 07 08:58:49 primo07 sudo[3987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:49 primo07 sudo[3987]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:49 primo07 sudo[3990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Jul 07 08:58:49 primo07 sudo[3990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:49 primo07 sudo[3990]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:49 primo07 volumio[3283]: info: MP1 Muting HDMI Output Jul 07 08:58:49 primo07 volumio[3283]: info: Starting CEC Event listener Jul 07 08:58:49 primo07 volumio[3283]: info: Volumio Calling Home Jul 07 08:58:49 primo07 volumio[3283]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jul 07 08:58:49 primo07 volumio[3283]: (node:3283) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jul 07 08:58:49 primo07 volumio[3283]: (Use `node --trace-deprecation ...` to show where the warning was created) Jul 07 08:58:49 primo07 volumio[3283]: info: Stopping AccessToken refresher cron for QOBUZ Jul 07 08:58:49 primo07 volumio[3283]: info: AccessToken refresher cron started for QOBUZ Jul 07 08:58:49 primo07 volumio[3283]: info: Adding QOBUZ REST API Endpoints Jul 07 08:58:49 primo07 volumio[3283]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 07 08:58:49 primo07 volumio[3283]: info: Serial port opened successfully Jul 07 08:58:49 primo07 volumio[3283]: info: Sending serial start messages Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:49 primo07 volumio[3283]: info: Reporting MCU Network Status: 2 Jul 07 08:58:49 primo07 volumio[3283]: error: Cannot start Volumio Streaming Daemon Jul 07 08:58:49 primo07 volumio[3283]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 07 08:58:49 primo07 volumio[3283]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 07 08:58:49 primo07 volumio[3283]: info: RAAT Albumart path created successfully Jul 07 08:58:49 primo07 volumio[3283]: info: Bluetooth name changed to Primo07 Jul 07 08:58:49 primo07 volumio[3283]: info: MPD Permissions set Jul 07 08:58:49 primo07 volumio[3283]: info: MPD Permissions set Jul 07 08:58:49 primo07 volumio[3283]: info: Upmpdcli Daemon Started Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Jul 07 08:58:49 primo07 volumio[3283]: info: Setting Device Volume Override Jul 07 08:58:49 primo07 volumio[3283]: info: Applying Volume Override Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 07 08:58:49 primo07 volumio[3283]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 07 08:58:49 primo07 volumio[3283]: info: Enabling external Volume Control Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:49 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:49 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:49 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:49 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:49 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:49 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:50 primo07 volumio[3283]: info: Executing endpoint qc_getconfig Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.047 [3950.3950] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jul 07 08:58:50 primo07 volumio[3283]: info: Spotify config file written Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 07 08:58:50 primo07 volumio[3283]: info: Setting Additional System Software info: Hardware Revision: 2.1 Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 07 08:58:50 primo07 volumio[3283]: info: Setting HW Firmware info: undefined Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 07 08:58:50 primo07 volumio[3283]: info: Setting HW Version info: 2.1 Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Jul 07 08:58:50 primo07 volumio[3283]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.7 Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Jul 07 08:58:50 primo07 volumio[3283]: info: Setting HW Firmware info: 0.3.7 Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Jul 07 08:58:50 primo07 volumio[3283]: info: Setting HW Version info: 2.1 Jul 07 08:58:50 primo07 volumio[3283]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jul 07 08:58:50 primo07 volumio[3283]: info: MCU Signalled Headphone Mode Disabled Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jul 07 08:58:50 primo07 volumio[3283]: info: Reporting Headphone State: false Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: Updating RAAT Signal Path Jul 07 08:58:50 primo07 volumio[3283]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Jul 07 08:58:50 primo07 volumio[3283]: info: MCU Signalled Sleep Mode Disabled Jul 07 08:58:50 primo07 sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 07 08:58:50 primo07 sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:50 primo07 volumio[3283]: info: Enabling Advanced system settings configuration Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Jul 07 08:58:50 primo07 volumio[3283]: info: Additional UI Settings Added for plugin music_service/inputs Jul 07 08:58:50 primo07 volumio[3283]: info: MCU Signalled Auto Boot Mode On Power Disabled Jul 07 08:58:50 primo07 sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jul 07 08:58:50 primo07 sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:50 primo07 systemd[1]: Started go-librespot Daemon. Jul 07 08:58:50 primo07 sudo[4015]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:50 primo07 go-librespot[4019]: Librespot-go daemon starting... Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.297 [3950.3950] INFO VolumeManager: [0xab60d380]: Setting new playback volume: 75 Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.300 [3950.3950] INFO VolumeManager: [0xab60d380]: Setting new mute state: 0 Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.301 [3950.3950] INFO QobuzConnect: [0xab60dd50]: Client initialized! Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.301 [3950.3950] INFO SampleApp: Starting Avahi advertising, name: Primo07, service name: _qobuz-connect._tcp Jul 07 08:58:50 primo07 kernel: fb: osd[0] enable: 1 (Xorg) Jul 07 08:58:50 primo07 sudo[4018]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:50 primo07 volumio[3283]: 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 07 08:58:50 primo07 volumio[3283]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jul 07 08:58:50 primo07 volumio[3283]: info: QobuzConnect: QOBUZ Connect daemon connected Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.346 [3950.3950] INFO LocalConfigManager: [0xab60cc60]: Starting Local Configuration server Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.346 [3950.3950] INFO SampleApp: Starting Local configuration server Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.347 [3950.3950] INFO SampleApp: Playback volume changed: 75 Jul 07 08:58:50 primo07 qobuz-connect[3950]: 20250707 08:58:50.348 [3950.3950] INFO SampleApp: Connected to UNIX socket client 0xab602ed8 Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 go-librespot[4019]: time="2025-07-07T08:58:50+08:00" level=info msg="generated new device id: c6261fd7bbf5fb5e84f717d5aef1cf0cb2d68a28" Jul 07 08:58:50 primo07 go-librespot[4019]: time="2025-07-07T08:58:50+08:00" level=debug msg="stored credentials found for mahusaina" Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 07 08:58:50 primo07 volumio[3283]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 07 08:58:50 primo07 volumio[3283]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jul 07 08:58:50 primo07 volumio[3283]: 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 07 08:58:50 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:50 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:50 primo07 volumio[3283]: info: Volumio called home Jul 07 08:58:50 primo07 sudo[4041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jul 07 08:58:50 primo07 sudo[4041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:50 primo07 sudo[4041]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:50 primo07 mpd[3935]: Jul 07 08:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 07 08:58:50 primo07 systemd[1]: Started Music Player Daemon. Jul 07 08:58:50 primo07 sudo[3918]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:50 primo07 sudo[3906]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:50 primo07 volumio[3283]: info: MRS: Found cast device: OLED55C3PSA-39098f35a8b87d3b0eaf76ac4a406618 Jul 07 08:58:50 primo07 volumio[3283]: info: Adding audio output: Jul 07 08:58:50 primo07 sudo[4046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 07 08:58:50 primo07 sudo[4046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:50 primo07 volumio[3283]: Upnp client error: Error: This socket has been ended by the other party Jul 07 08:58:51 primo07 systemd[1]: Started Volumio Bluetooth Module. Jul 07 08:58:51 primo07 sudo[4046]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:51 primo07 volumio[3283]: info: Starting Shairport Sync Jul 07 08:58:51 primo07 volumio[3283]: info: Starting Shairport Sync Jul 07 08:58:51 primo07 sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 07 08:58:51 primo07 sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:51 primo07 volumiobt[4050]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 07 08:58:51 primo07 sudo[4054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 08:58:51 primo07 sudo[4054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:51 primo07 volumio[3283]: info: Starting Shairport Sync Jul 07 08:58:51 primo07 sudo[4052]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:51 primo07 go-librespot[4019]: time="2025-07-07T08:58:51+08:00" level=debug msg="obtained new client token: AACe3f0xYqQKXGaDgJss1gQsu8IhsETx2uUWnpdFx6o+bLEV0Kv02tp5KgKKOaa+7dt9FF/Rf76cL9EOSKr37l6BYjt8eG6DODRE+kqYFPFT7HvDlhUAOskasYDeSNBrxKtDMlMdNu51bvxArZrMW1rKWBNX62jxh98rbAMH84PeEkyCFX7/lf64zZB++aJz0rB/MfIOZlDNG8PM0NsO4zicUI6WaQrpAh5f56DCT4sO7TxsahZkpKe2O5e1X70=" Jul 07 08:58:51 primo07 sudo[4058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 08:58:51 primo07 sudo[4058]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:51 primo07 volumio[3283]: info: Adding Inputs via Serial API Jul 07 08:58:51 primo07 volumio[3283]: info: Adding Advanced Audio Settings via Serial API Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Jul 07 08:58:51 primo07 volumio[3283]: info: Additional UI Settings Added for plugin music_service/inputs Jul 07 08:58:51 primo07 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 08:58:51 primo07 systemd[1]: shairport-sync.service: Succeeded. Jul 07 08:58:51 primo07 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:51 primo07 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:51 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:51 primo07 sudo[4054]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:51 primo07 sudo[4062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 07 08:58:51 primo07 sudo[4062]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:51 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:51 primo07 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 08:58:51 primo07 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 07 08:58:51 primo07 systemd[1]: shairport-sync.service: Succeeded. Jul 07 08:58:51 primo07 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 sudo[4058]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:51 primo07 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 07 08:58:51 primo07 systemd[1]: shairport-sync.service: Succeeded. Jul 07 08:58:51 primo07 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 07 08:58:51 primo07 go-librespot[4019]: time="2025-07-07T08:58:51+08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jul 07 08:58:51 primo07 sudo[4062]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::servicePushState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 volumio[3283]: 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 07 08:58:51 primo07 volumio[3283]: verbose: CURRENT POSITION 0 Jul 07 08:58:51 primo07 volumio[3283]: info: CoreStateMachine::syncState stateService play Jul 07 08:58:51 primo07 volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumiosetSourceActiveno-source Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:51 primo07 volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 07 08:58:51 primo07 volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 07 08:58:51 primo07 go-librespot[4019]: time="2025-07-07T08:58:51+08:00" level=debug msg="completed keyexchange" Jul 07 08:58:51 primo07 volumio[3283]: error: MPD error: The expression evaluated to a falsy value: Jul 07 08:58:51 primo07 volumio[3283]: assert.ok(self.idling) Jul 07 08:58:51 primo07 volumio[3283]: error: The expression evaluated to a falsy value: Jul 07 08:58:51 primo07 volumio[3283]: assert.ok(self.idling) Jul 07 08:58:51 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:51 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:51 primo07 systemd[1]: systemd-timedated.service: Succeeded. Jul 07 08:58:51 primo07 volumio[3283]: info: Shairport-Sync Started Jul 07 08:58:51 primo07 volumio[3283]: Error adding Membership: Error: addMembership EINVAL Jul 07 08:58:51 primo07 volumio[3283]: info: Shairport-Sync Started Jul 07 08:58:51 primo07 volumio[3283]: info: Shairport-Sync Started Jul 07 08:58:51 primo07 volumio[3283]: info: MPD running with PID3935 Jul 07 08:58:51 primo07 volumio[3283]: ,establishing connection Jul 07 08:58:51 primo07 volumio[3283]: info: Volumio BT Module successfully started Jul 07 08:58:51 primo07 volumio[3283]: error: updateQueue error: null Jul 07 08:58:51 primo07 volumio[3283]: info: Access Token successfully retrieved Jul 07 08:58:51 primo07 volumio[3283]: info: TidalConnect service stoped! Jul 07 08:58:51 primo07 volumio[3283]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 07 08:58:51 primo07 volumio[3283]: SPOTIFY: BQCYQVTn9m-rH93ZboCTPBm4s4vb3Vuj626uVPd7na3s7hJOmcugZGBbCMAQHIOg4d-orXLiw5ZgjU4TmpYUnR6cyICIevcsIA9zYhWc4h81IJ-U_yDGBVm017tU_TslQq7ShbZXnINCCWdV556o5TSBA93cmX-uBHLqbt7mUqFSTL4Yymr_dL8gq82_IhTydRHOlMeRTVEyAlfVNA_P7G5GPystVfG8II-5ui07Hz88cNZygvJ8n5FvA0fOFuPxpGJI_jb_KrEGiD6MVWWYNyIkzQ Jul 07 08:58:51 primo07 volumio[3283]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 07 08:58:51 primo07 volumio[3283]: info: New Spotify access token = BQCYQVTn9m-rH93ZboCTPBm4s4vb3Vuj626uVPd7na3s7hJOmcugZGBbCMAQHIOg4d-orXLiw5ZgjU4TmpYUnR6cyICIevcsIA9zYhWc4h81IJ-U_yDGBVm017tU_TslQq7ShbZXnINCCWdV556o5TSBA93cmX-uBHLqbt7mUqFSTL4Yymr_dL8gq82_IhTydRHOlMeRTVEyAlfVNA_P7G5GPystVfG8II-5ui07Hz88cNZygvJ8n5FvA0fOFuPxpGJI_jb_KrEGiD6MVWWYNyIkzQ Jul 07 08:58:51 primo07 volumio[3283]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="completed challenge" Jul 07 08:58:52 primo07 volumio[3283]: info: MCU Signalled Playback Inactive Jul 07 08:58:52 primo07 volumio[3283]: error: updateQueue error: null Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="authenticated as mahusaina" Jul 07 08:58:52 primo07 sudo[4078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 07 08:58:52 primo07 sudo[4078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:52 primo07 volumio[3283]: info: Initializing I2S Bus Jul 07 08:58:52 primo07 volumiobt[4050]: pulseaudio: no process found Jul 07 08:58:52 primo07 sudo[4078]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:52 primo07 volumio[3283]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 07 08:58:52 primo07 volumio[3283]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="authenticated as mahusaina" Jul 07 08:58:52 primo07 sudo[4085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 07 08:58:52 primo07 sudo[4085]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 07 08:58:52 primo07 volumio[3283]: 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: 3 Jul 07 08:58:52 primo07 dbus-daemon[2827]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=4087 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 07 08:58:52 primo07 systemd[1]: Started Volumio Tidal Connect Service. Jul 07 08:58:52 primo07 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 07 08:58:52 primo07 sudo[4085]: pam_unix(sudo:session): session closed for user root Jul 07 08:58:52 primo07 kernel: IPVS: Creating netns size=1928 id=3 Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 07 08:58:52 primo07 volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 07 08:58:52 primo07 dbus-daemon[2827]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 07 08:58:52 primo07 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 07 08:58:52 primo07 kernel: aml_tdm_open Jul 07 08:58:52 primo07 kernel: Not init audio effects Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Successfully called chroot. Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Successfully dropped privileges. Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Successfully limited resources. Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Running. Jul 07 08:58:52 primo07 volumio[3283]: info: Successfully retrieved User Session From TIDAL Jul 07 08:58:52 primo07 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 07 08:58:52 primo07 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 07 08:58:52 primo07 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050f43218, id(1), clksel(1) Jul 07 08:58:52 primo07 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 07 08:58:52 primo07 kernel: dump_pcm_setting(ffffffc050f43218) Jul 07 08:58:52 primo07 kernel: pcm_mode(1) Jul 07 08:58:52 primo07 kernel: sysclk(11289600) Jul 07 08:58:52 primo07 kernel: sysclk_bclk_ratio(4) Jul 07 08:58:52 primo07 kernel: bclk(2822400) Jul 07 08:58:52 primo07 kernel: bclk_lrclk_ratio(64) Jul 07 08:58:52 primo07 kernel: lrclk(44100) Jul 07 08:58:52 primo07 kernel: tx_mask(0x3) Jul 07 08:58:52 primo07 kernel: rx_mask(0x3) Jul 07 08:58:52 primo07 kernel: slots(2) Jul 07 08:58:52 primo07 kernel: slot_width(32) Jul 07 08:58:52 primo07 kernel: lane_mask_in(0x2) Jul 07 08:58:52 primo07 kernel: lane_mask_out(0x1) Jul 07 08:58:52 primo07 kernel: lane_oe_mask_in(0x0) Jul 07 08:58:52 primo07 kernel: lane_oe_mask_out(0x0) Jul 07 08:58:52 primo07 kernel: lane_lb_mask_in(0x0) Jul 07 08:58:52 primo07 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 07 08:58:52 primo07 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 07 08:58:52 primo07 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 07 08:58:52 primo07 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 07 08:58:52 primo07 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 07 08:58:52 primo07 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 07 08:58:52 primo07 kernel: aml_tdm_prepare(), reset fddr Jul 07 08:58:52 primo07 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 07 08:58:52 primo07 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 07 08:58:52 primo07 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 07 08:58:52 primo07 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 07 08:58:52 primo07 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 07 08:58:52 primo07 kernel: spdif_a is set to enable Jul 07 08:58:52 primo07 dbus-daemon[2827]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=4097 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Watchdog thread running. Jul 07 08:58:52 primo07 rtkit-daemon[4097]: Canary thread running. Jul 07 08:58:52 primo07 systemd[1]: Starting Authorization Manager... Jul 07 08:58:52 primo07 volumio[3894]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 07 08:58:52 primo07 volumio[3283]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="dealer connection opened" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="received connection id: MjFkY2QxOTgtNDM3Ni00NzZjLWE0MTgtYzg4ZDM4MWFkZTg2K2RlYWxlcit0Y3A6Ly8wYWIxNThjNi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArNUZDNEQwRTA5NENDQTI0NEM4RDA0MEQ0NTlEMkQ3RTYxRTMxQjk3MjU2QzIzMEIzNUY2NjcyQ0I0NDUzRDE5RA==" Jul 07 08:58:52 primo07 polkitd[4113]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 07 08:58:52 primo07 dbus-daemon[2827]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 07 08:58:52 primo07 systemd[1]: Started Authorization Manager. Jul 07 08:58:52 primo07 volumio[3283]: SPOTIFY: User informations: {"country":"MY","display_name":"mahusaina","email":"mahusaina@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/mahusaina"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/mahusaina","id":"mahusaina","images":[],"product":"premium","type":"user","uri":"spotify:user:mahusaina"} Jul 07 08:58:52 primo07 volumio[3283]: info: Spotify Successfully logged in Jul 07 08:58:52 primo07 volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 08:58:52 primo07 volumio[3283]: info: [1751849932763] CoreMusicLibrary::Adding element Spotify Jul 07 08:58:52 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:52 primo07 volumio[3283]: Cannot find translation for source Spotify Jul 07 08:58:52 primo07 go-librespot[4019]: time="2025-07-07T08:58:52+08:00" level=debug msg="put connect state because NEW_DEVICE" Jul 07 08:58:53 primo07 volumio[3283]: info: Successfully retrieved User Subscription From TIDAL Jul 07 08:58:53 primo07 volumio[3283]: info: Adding TIDAL to Browse Sources Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 07 08:58:53 primo07 volumio[3283]: info: [1751849933007] CoreMusicLibrary::Adding element TIDAL Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:53 primo07 volumio[3283]: Cannot find translation for source Spotify Jul 07 08:58:53 primo07 volumio[3283]: Cannot find translation for source TIDAL Jul 07 08:58:53 primo07 volumio[3283]: info: Stopping AccessToken refresher cron Jul 07 08:58:53 primo07 volumio[3283]: info: AccessToken refresher cron started Jul 07 08:58:53 primo07 volumio[3283]: info: Adding TIDAL REST API Endpoints Jul 07 08:58:53 primo07 volumio[3283]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 07 08:58:53 primo07 volumio[3283]: info: Executing endpoint tc_getconfig Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 07 08:58:53 primo07 vtcs[4095]: STARTING TidalConnect services, version: 1.4.0.34 Jul 07 08:58:53 primo07 vtcs[4095]: STARTED TidalConnect services. Jul 07 08:58:53 primo07 volumio[3283]: info: Executing endpoint tc_connect Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 07 08:58:53 primo07 volumio[3283]: info: Connecting to TidalConnect Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::servicePushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:53 primo07 pulseaudio[4087]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 08:58:53 primo07 pulseaudio[4087]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: 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 07 08:58:53 primo07 volumio[3283]: verbose: CURRENT POSITION 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::syncState stateService stop Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::syncState currentStatus play Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::stPlaybackTimer Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::servicePushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:53 primo07 volumiobt[4050]: Applying permissions Jul 07 08:58:53 primo07 volumiobt[4050]: Setting BT discoverable and pairable Jul 07 08:58:53 primo07 bluetoothd[3211]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: 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 07 08:58:53 primo07 volumio[3283]: verbose: CURRENT POSITION 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::syncState stateService stop Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::syncState currentStatus stop Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:53 primo07 bluetoothd[3211]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jul 07 08:58:53 primo07 pulseaudio[4087]: 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 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:53 primo07 volumio[3283]: info: No code Jul 07 08:58:53 primo07 volumio[3283]: info: CoreStateMachine::pushState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioPushState Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:53 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output update for this device Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Pushing multiroomSync output Jul 07 08:58:53 primo07 volumiobt[4050]: Agent registered Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# -e power on Jul 07 08:58:53 primo07 volumiobt[4050]: Invalid command in menu main: -e Jul 07 08:58:53 primo07 volumiobt[4050]: Jul 07 08:58:53 primo07 volumiobt[4050]: Use "help" for a list of available commands in a menu. Jul 07 08:58:53 primo07 volumiobt[4050]: Use "menu " if you want to enter any submenu. Jul 07 08:58:53 primo07 volumiobt[4050]: Use "back" if you want to return to menu main. Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# agent on Jul 07 08:58:53 primo07 volumiobt[4050]: Agent is already registered Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# discoverable on Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# pairable on Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# agent NoInputNoOutput Jul 07 08:58:53 primo07 volumiobt[4050]: Agent is already registered Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# default-agent Jul 07 08:58:53 primo07 volumiobt[4050]: [bluetooth]# quit Jul 07 08:58:53 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Getting audio outputs on start Jul 07 08:58:53 primo07 volumio[3283]: info: MRS: Requesting all other devices output Jul 07 08:58:53 primo07 volumiobt[4050]: [59B blob data] Jul 07 08:58:53 primo07 volumio[3283]: info: go-librespot daemon successfully initialized Jul 07 08:58:53 primo07 kernel: asoc-aml-card auge_sound: tdm playback stop Jul 07 08:58:53 primo07 kernel: spdif_a is set to disable Jul 07 08:58:53 primo07 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 07 08:58:53 primo07 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 07 08:58:53 primo07 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 07 08:58:53 primo07 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 07 08:58:53 primo07 volumio[3283]: info: Successfully initialized Primo I2S Bus Jul 07 08:58:55 primo07 volumio-remote-updater[2815]: [2025-07-07 08:58:55] [connect] Successful connection Jul 07 08:58:55 primo07 volumio-remote-updater[2815]: [2025-07-07 08:58:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751849935 101 Jul 07 08:58:55 primo07 volumio[3283]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jul 07 08:58:55 primo07 volumio[3283]: info: TidalConnect service started! Jul 07 08:58:55 primo07 volumio[3283]: info: Completed starting Core Plugins Jul 07 08:58:55 primo07 volumio[3283]: info: ------------------------------------------- Jul 07 08:58:55 primo07 volumio[3283]: info: ----- MyVolumio plugins startup ---- Jul 07 08:58:55 primo07 volumio[3283]: info: ------------------------------------------- Jul 07 08:58:55 primo07 volumio[3283]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 07 08:58:55 primo07 volumio[3283]: 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: 5 Jul 07 08:58:56 primo07 volumio[3283]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 07 08:58:56 primo07 volumio[3283]: info: Initializing connection to go-librespot Websocket Jul 07 08:58:56 primo07 go-librespot[4019]: time="2025-07-07T08:58:56+08:00" level=debug msg="new websocket client" Jul 07 08:58:56 primo07 volumio[3283]: info: Connection to go-librespot Websocket established Jul 07 08:58:57 primo07 go-librespot[4019]: time="2025-07-07T08:58:57+08:00" level=debug msg="handling transfer player command from 0f888303f22a526ba9196273a93041a693907edd" Jul 07 08:58:57 primo07 go-librespot[4019]: time="2025-07-07T08:58:57+08:00" level=trace msg="fetched new page 0 with 11 items (list: 11)" Jul 07 08:58:57 primo07 go-librespot[4019]: time="2025-07-07T08:58:57+08:00" level=debug msg="loading track spotify:track:2HI6q52N8TZYElRN2rS2sB (paused: true, position: 101629ms)" Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=trace msg="emitting websocket event: will_play" Jul 07 08:58:58 primo07 volumio[3283]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2HI6q52N8TZYElRN2rS2sB","play_origin":"playlist"}} Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:2HI6q52N8TZYElRN2rS2sB" Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="requested aes key for file c65d0819f0b20c901239da03d2a26eb89ab16fa7, gid: 2HI6q52N8TZYElRN2rS2sB" Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1336" Jul 07 08:58:58 primo07 volumio[3283]: 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 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 08:58:58 primo07 volumio[3283]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1112" Jul 07 08:58:58 primo07 volumio[3283]: info: Received Get System Info Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 07 08:58:58 primo07 volumio[3283]: info: Discovery: Getting this device information Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: Listing playlists Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 07 08:58:58 primo07 volumio[3283]: info: Received Get System Info Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 07 08:58:58 primo07 volumio[3283]: info: Discovery: Getting this device information Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetVisibleSources Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 07 08:58:58 primo07 volumio[3283]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 07 08:58:58 primo07 volumio[3283]: info: Received Get System Info Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 07 08:58:58 primo07 volumio[3283]: info: Discovery: Getting this device information Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 07 08:58:58 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:58 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:58 primo07 volumio[3283]: info: Listing playlists Jul 07 08:58:58 primo07 go-librespot[4019]: time="2025-07-07T08:58:58+08:00" level=debug msg="fetched first chunk of 27, total size is 13792616 bytes" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 3/26, size: 524288" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 1/26, size: 524288" Jul 07 08:58:59 primo07 volumio[3283]: info: Checking for updated MCU Firmware Jul 07 08:58:59 primo07 volumio[3283]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 07 08:58:59 primo07 volumio[3283]: info: Firware on device is on latest version, no need to update Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 10/26, size: 524288" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 2/26, size: 524288" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 7/26, size: 524288" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 8/26, size: 524288" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=trace msg="seek to 101629ms (diff: 64ms, samples: 4481838, bytes: 3970530)" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="fetched chunk 9/26, size: 524288" Jul 07 08:58:59 primo07 kernel: aml_tdm_open Jul 07 08:58:59 primo07 kernel: Not init audio effects Jul 07 08:58:59 primo07 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 07 08:58:59 primo07 go-librespot[4019]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 32768 Jul 07 08:58:59 primo07 go-librespot[4019]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 32768 Jul 07 08:58:59 primo07 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 07 08:58:59 primo07 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 07 08:58:59 primo07 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 07 08:58:59 primo07 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc050f43218, id(1), clksel(1) Jul 07 08:58:59 primo07 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 07 08:58:59 primo07 kernel: dump_pcm_setting(ffffffc050f43218) Jul 07 08:58:59 primo07 kernel: pcm_mode(1) Jul 07 08:58:59 primo07 kernel: sysclk(11289600) Jul 07 08:58:59 primo07 kernel: sysclk_bclk_ratio(4) Jul 07 08:58:59 primo07 kernel: bclk(2822400) Jul 07 08:58:59 primo07 kernel: bclk_lrclk_ratio(64) Jul 07 08:58:59 primo07 kernel: lrclk(44100) Jul 07 08:58:59 primo07 kernel: tx_mask(0x3) Jul 07 08:58:59 primo07 kernel: rx_mask(0x3) Jul 07 08:58:59 primo07 kernel: slots(2) Jul 07 08:58:59 primo07 kernel: slot_width(32) Jul 07 08:58:59 primo07 kernel: lane_mask_in(0x2) Jul 07 08:58:59 primo07 kernel: lane_mask_out(0x1) Jul 07 08:58:59 primo07 kernel: lane_oe_mask_in(0x0) Jul 07 08:58:59 primo07 kernel: lane_oe_mask_out(0x0) Jul 07 08:58:59 primo07 kernel: lane_lb_mask_in(0x0) Jul 07 08:58:59 primo07 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 07 08:58:59 primo07 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 07 08:58:59 primo07 kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jul 07 08:58:59 primo07 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 07 08:58:59 primo07 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 07 08:58:59 primo07 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 07 08:58:59 primo07 kernel: aml_tdm_prepare(), reset fddr Jul 07 08:58:59 primo07 kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Jul 07 08:58:59 primo07 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 07 08:58:59 primo07 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 07 08:58:59 primo07 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 07 08:58:59 primo07 kernel: aml_tdm_prepare(), reset fddr Jul 07 08:58:59 primo07 kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Jul 07 08:58:59 primo07 kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jul 07 08:58:59 primo07 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 07 08:58:59 primo07 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 07 08:58:59 primo07 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 07 08:58:59 primo07 kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Jul 07 08:58:59 primo07 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 07 08:58:59 primo07 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=info msg="loaded track \"Yang Bertakhta Akhirnya\" (uri: spotify:track:2HI6q52N8TZYElRN2rS2sB, paused: true, position: 101629ms, duration: 348466ms)" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=trace msg="emitting websocket event: metadata" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=trace msg="emitting websocket event: active" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="sending successful reply for delaer request" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 07 08:58:59 primo07 volumio[3283]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2HI6q52N8TZYElRN2rS2sB","name":"Yang Bertakhta Akhirnya","artist_names":["M. Nasir"],"album_name":"SOLO","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028de2ce8265252e840e6ea5f4","position":101629,"duration":348466,"release_date":"year:1989","track_number":4,"disc_number":1}} Jul 07 08:58:59 primo07 volumio[3283]: SPOTIFY: received: {"type":"active","data":null} Jul 07 08:58:59 primo07 volumio[3283]: info: Aligning Spotify Volume to Volumio Volume Jul 07 08:58:59 primo07 volumio[3283]: info: CoreCommandRouter::volumioGetState Jul 07 08:58:59 primo07 volumio[3283]: info: CorePlayQueue::getTrack 0 Jul 07 08:58:59 primo07 volumio[3283]: info: Setting Spotify Volume from Volumio: 100 Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 07 08:58:59 primo07 go-librespot[4019]: time="2025-07-07T08:58:59+08:00" level=trace msg="emitting websocket event: paused" Jul 07 08:58:59 primo07 volumio[3283]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:2HI6q52N8TZYElRN2rS2sB","play_origin":"playlist"}} Jul 07 08:58:59 primo07 volumio[3283]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 08:58:59 primo07 volumio[3283]: TypeError: Cannot read property 'service' of undefined Jul 07 08:58:59 primo07 volumio[3283]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 07 08:58:59 primo07 volumio[3283]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jul 07 08:58:59 primo07 volumio[3283]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 07 08:58:59 primo07 volumio[3283]: at WebSocket.emit (events.js:400:28) Jul 07 08:58:59 primo07 volumio[3283]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1203:20) Jul 07 08:58:59 primo07 volumio[3283]: at Receiver.emit (events.js:400:28) Jul 07 08:58:59 primo07 volumio[3283]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:608:16 Jul 07 08:58:59 primo07 volumio[3283]: at internal/process/task_queues.js:141:7 Jul 07 08:58:59 primo07 volumio[3283]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Jul 07 08:58:59 primo07 volumio[3283]: at AsyncResource.runMicrotask (internal/process/task_queues.js:138:8) Jul 07 08:58:59 primo07 volumio[3283]: at runMicrotasks () Jul 07 08:58:59 primo07 volumio[3283]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Jul 07 08:58:59 primo07 volumio[3283]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 07 08:58:59 primo07 volumio[3894]: ..................................................................................++++ Jul 07 08:59:00 primo07 volumio[3894]: .....++++ Jul 07 08:59:00 primo07 volumio[3894]: e is 65537 (0x010001) Jul 07 08:59:00 primo07 volumio[3894]: writing RSA key Jul 07 08:59:00 primo07 sudo[4168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-07 08:58 Jul 07 08:59:00 primo07 sudo[4168]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 07:02:33 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="905581800126f97a879a13768b481efc"