-- Logs begin at Tue 2025-12-09 16:21:21 UTC, end at Tue 2025-12-16 17:30:18 UTC. -- Dec 16 17:29:00 primo volumio[3530]: info: Successfully started MPD Monitor Dec 16 17:29:00 primo sudo[4252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 16 17:29:00 primo sudo[4252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:00 primo systemd[1]: Stopping MPD Monitor Service... Dec 16 17:29:00 primo systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 16 17:29:00 primo systemd[1]: mpd_monitor.service: Succeeded. Dec 16 17:29:00 primo systemd[1]: Stopped MPD Monitor Service. Dec 16 17:29:00 primo volumio[3530]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 16 17:29:00 primo systemd[1]: Started MPD Monitor Service. Dec 16 17:29:00 primo volumio[3530]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 16 17:29:00 primo sudo[4252]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:00 primo mpd_monitor.sh[4254]: MPD Monitor Service: Starting MPD Monitor Service Dec 16 17:29:00 primo volumio[3530]: info: Successfully started MPD Monitor Dec 16 17:29:01 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Dec 16 17:29:01 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:01 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:01 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:01 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:01 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:01 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:02 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:02 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:02 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:02 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 17:29:02 primo sudo[4276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 17:29:02 primo sudo[4276]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4276]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4281]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4289]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4281]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4289]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4295]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 17:29:02 primo sudo[4302]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4295]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo volumio[3530]: 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: 8 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 17:29:02 primo sudo[4305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 17:29:02 primo sudo[4305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4302]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4305]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 17:29:02 primo sudo[4310]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4310]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4315]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 17:29:02 primo sudo[4328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo sudo[4322]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4328]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo sudo[4336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 17:29:02 primo sudo[4336]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 17:29:02 primo sudo[4339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 17:29:02 primo sudo[4339]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:02 primo sudo[4336]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 17:29:02 primo sudo[4339]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 17:29:02 primo volumio[3530]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 16 17:29:02 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:02 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:02 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:02 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: Listing playlists Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:03 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 17:29:03 primo volumio[3530]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 16 17:29:03 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: Listing playlists Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:03 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:03 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:03 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:03 primo volumio[3530]: info: Checking for updated MCU Firmware Dec 16 17:29:03 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 17:29:03 primo volumio[3530]: info: Firware on device is on latest version, no need to update Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:04 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:04 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:04 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:04 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:04 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:04 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:04 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:04 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:04 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:04 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:05 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:05 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:05 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:05 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:05 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 17:29:06 primo sudo[4385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 17:29:06 primo sudo[4385]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:06 primo sudo[4385]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:06 primo sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 17:29:06 primo sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:06 primo sudo[4387]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:06 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-A536B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 16 17:29:06 primo sudo[4393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 17:29:06 primo sudo[4393]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:06 primo sudo[4393]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:06 primo sudo[4395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 17:29:06 primo sudo[4395]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:06 primo sudo[4395]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:07 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.7 UA: Mozilla/5.0 (Linux; Android 16; SM-A536B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/142.0.7444.171 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:07 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 17:29:07 primo volumio[3530]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 16 17:29:07 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:07 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:07 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:07 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:07 primo volumio[3530]: info: Listing playlists Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 17:29:07 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 16 17:29:08 primo volumio[3530]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 16 17:29:09 primo volumio[3530]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 17:29:09 primo volumio[3530]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:10 primo volumio[3530]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 17:29:10 primo volumio[3530]: info: MyVolumio login type: Token Dec 16 17:29:10 primo volumio[3530]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 17:29:10 primo volumio[3530]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:10 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:10 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:10 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:29:10 primo volumio[3530]: info: CoreStateMachine::getQueue Dec 16 17:29:10 primo volumio[3530]: info: CorePlayQueue::getQueue Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:10 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:10 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:10 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:10 primo volumio[3530]: info: Executing endpoint metavolumio Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 17:29:10 primo volumio[3530]: error: MyVolumio Custom Token format not valid, refreshing it Dec 16 17:29:10 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 17:29:10 primo volumio[3530]: info: MyVolumio login type: Token Dec 16 17:29:11 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 16 17:29:11 primo volumio[3530]: info: MyVolumio login type: Token Dec 16 17:29:11 primo volumio[3530]: info: MyVolumio login type: Token Dec 16 17:29:11 primo volumio[3530]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 16 17:29:12 primo volumio[3530]: info: MyVolumio token set successfully Dec 16 17:29:12 primo volumio[3530]: info: MYVOLUMIO: Adding device Dec 16 17:29:12 primo volumio[3530]: info: MYVOLUMIO: Evaluating Server Dec 16 17:29:12 primo volumio[3530]: info: MyVolumio status changed Dec 16 17:29:12 primo volumio[3530]: info: Streaming services startup Dec 16 17:29:12 primo volumio[3530]: info: Starting Streaming Daemon Dec 16 17:29:12 primo volumio[3530]: info: Removing browser output: myVolumio user plan is not superstar Dec 16 17:29:12 primo volumio[3530]: info: Removing audio output: Dec 16 17:29:12 primo volumio[3530]: info: Stoppping Tunnel 1 Dec 16 17:29:12 primo sudo[4443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 17:29:12 primo sudo[4443]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:12 primo sudo[4443]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:12 primo sudo[4446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 16 17:29:12 primo sudo[4446]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:12 primo volumio[3530]: error: Cannot start Volumio Streaming Daemon Dec 16 17:29:12 primo volumio[3530]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 17:29:12 primo volumio[3530]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 17:29:13 primo sudo[4446]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:13 primo volumio[3530]: info: Remote SSH Stopped Dec 16 17:29:13 primo volumio[3530]: info: Setting Geolocation for MyVolumio to eu4 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:13 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:13 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:13 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:13 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:13 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:13 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:13 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:13 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:13 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:13 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:13 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:13 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:13 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:13 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:13 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 16 17:29:13 primo volumio[3530]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 17:29:13 primo volumio[3530]: info: handleBrowseUri took 166 milliseconds Dec 16 17:29:14 primo volumio[3530]: info: Preload queue cleared Dec 16 17:29:14 primo volumio[3530]: info: Preload queue cleared Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 17:29:14 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:14 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:14 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:14 primo volumio[3530]: info: Updating MyVolumio device info Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:14 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:14 primo volumio[3530]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 17:29:16 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 17:29:17 primo volumio[3530]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 17:29:17 primo volumio[3530]: info: Received Get System Version Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 17:29:17 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:17 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:17 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:17 primo volumio[3530]: info: MYVOLUMIO: Adding device Dec 16 17:29:17 primo volumio[3530]: info: MYVOLUMIO: Evaluating Server Dec 16 17:29:18 primo volumio[3530]: Searching plugin music_service/qobuz Dec 16 17:29:18 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 16 17:29:18 primo volumio[3530]: info: Setting Geolocation for MyVolumio to eu7 Dec 16 17:29:18 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:18 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:18 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:18 primo volumio[4003]: ................................................................................................................................................................................................................................................................................++++ Dec 16 17:29:19 primo volumio[3530]: Searching plugin music_service/qobuz Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 16 17:29:19 primo volumio[3530]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 16 17:29:19 primo volumio[3530]: info: searchQOBUZUri took 1087 milliseconds Dec 16 17:29:19 primo volumio[3530]: info: All search sources collected, pushing search results Dec 16 17:29:19 primo volumio[3530]: info: Updating MyVolumio device info Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:19 primo volumio[3530]: Searching plugin music_service/qobuz Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 16 17:29:19 primo volumio[3530]: error: MyVolumio Plugin failed to start in a timely fashion Dec 16 17:29:19 primo volumio[3530]: [Metrics] CommandRouter: 51s 950.38ms Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:19 primo volumio[3530]: info: VolumeController:: Setting startup Volume 100 Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: inputs , alsavolume Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::Close All Modals sent Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::Close All Modals sent Dec 16 17:29:19 primo volumio[3530]: info: CoreStateMachine::pushState Dec 16 17:29:19 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::volumioPushState Dec 16 17:29:19 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:19 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:19 primo volumio[3530]: info: MRS: Pushing multiroomSync output update for this device Dec 16 17:29:19 primo volumio[3530]: info: MRS: Pushing multiroomSync output Dec 16 17:29:20 primo volumio[3530]: info: CoreStateMachine::pushState Dec 16 17:29:20 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:20 primo volumio[3530]: info: CoreCommandRouter::volumioPushState Dec 16 17:29:20 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:20 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:20 primo volumio[3530]: info: MRS: Pushing multiroomSync output update for this device Dec 16 17:29:20 primo volumio[3530]: info: MRS: Pushing multiroomSync output Dec 16 17:29:20 primo kernel: aml_tdm_open Dec 16 17:29:20 primo kernel: Not init audio effects Dec 16 17:29:20 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 16 17:29:20 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 16 17:29:20 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 16 17:29:20 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 16 17:29:20 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d24d218, id(1), clksel(1) Dec 16 17:29:20 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Dec 16 17:29:20 primo kernel: dump_pcm_setting(ffffffc03d24d218) Dec 16 17:29:20 primo kernel: pcm_mode(1) Dec 16 17:29:20 primo kernel: sysclk(11289600) Dec 16 17:29:20 primo kernel: sysclk_bclk_ratio(4) Dec 16 17:29:20 primo kernel: bclk(2822400) Dec 16 17:29:20 primo kernel: bclk_lrclk_ratio(64) Dec 16 17:29:20 primo kernel: lrclk(44100) Dec 16 17:29:20 primo kernel: tx_mask(0x3) Dec 16 17:29:20 primo kernel: rx_mask(0x3) Dec 16 17:29:20 primo kernel: slots(2) Dec 16 17:29:20 primo kernel: slot_width(32) Dec 16 17:29:20 primo kernel: lane_mask_in(0x2) Dec 16 17:29:20 primo kernel: lane_mask_out(0x1) Dec 16 17:29:20 primo kernel: lane_oe_mask_in(0x0) Dec 16 17:29:20 primo kernel: lane_oe_mask_out(0x0) Dec 16 17:29:20 primo kernel: lane_lb_mask_in(0x0) Dec 16 17:29:20 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Dec 16 17:29:20 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Dec 16 17:29:20 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Dec 16 17:29:20 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Dec 16 17:29:20 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Dec 16 17:29:20 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Dec 16 17:29:20 primo kernel: aml_tdm_prepare(), reset fddr Dec 16 17:29:20 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Dec 16 17:29:20 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 16 17:29:20 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 16 17:29:20 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 16 17:29:20 primo kernel: aml_tdm_prepare(), reset fddr Dec 16 17:29:20 primo kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Dec 16 17:29:20 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Dec 16 17:29:20 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 16 17:29:20 primo kernel: tdm playback mute: 0, lane_cnt = 8 Dec 16 17:29:20 primo kernel: asoc-aml-card auge_sound: tdm playback enable Dec 16 17:29:20 primo kernel: spdif_a is set to enable Dec 16 17:29:20 primo volumio[3530]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 16 17:29:20 primo volumio[3530]: info: searchQOBUZUri took 1287 milliseconds Dec 16 17:29:20 primo volumio[3530]: info: All search sources collected, pushing search results Dec 16 17:29:20 primo volumio[3530]: Searching plugin music_service/qobuz Dec 16 17:29:20 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Dec 16 17:29:20 primo volumio[3530]: info: searchQOBUZUri took 1073 milliseconds Dec 16 17:29:20 primo volumio[3530]: info: All search sources collected, pushing search results Dec 16 17:29:20 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 17:29:20 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 17:29:21 primo volumio[3530]: No protocol specified Dec 16 17:29:21 primo volumio[3530]: xcb_connection_has_error() returned true Dec 16 17:29:21 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 17:29:21 primo volumio[3530]: info: searchQOBUZUri took 879 milliseconds Dec 16 17:29:21 primo volumio[3530]: info: All search sources collected, pushing search results Dec 16 17:29:23 primo volumio[4003]: ........................................................++++ Dec 16 17:29:23 primo volumio[4003]: e is 65537 (0x010001) Dec 16 17:29:23 primo volumio[4003]: writing RSA key Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 17:29:24 primo volumio-remote-updater[2827]: No test mode Dec 16 17:29:24 primo volumio-remote-updater[2827]: No alpha test mode Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 16 17:29:24 primo volumio[3530]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:24 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:24 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 16 17:29:24 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:24 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:24 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:24 primo volumio[3530]: verbose: New Socket.io Connection to 192.168.1.6:3000 from 192.168.1.7 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 16 17:29:24 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 16 17:29:24 primo kernel: asoc-aml-card auge_sound: tdm playback stop Dec 16 17:29:24 primo kernel: spdif_a is set to disable Dec 16 17:29:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Dec 16 17:29:24 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Dec 16 17:29:24 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 16 17:29:24 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 17:29:25 primo volumio[3530]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:25 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 17:29:25 primo volumio[3530]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 16 17:29:25 primo volumio[3530]: info: Received Get System Version Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 17:29:25 primo volumio[3530]: info: Received Get System Info Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 17:29:25 primo volumio[3530]: info: Discovery: Getting this device information Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::volumioGetState Dec 16 17:29:25 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:25 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 17:29:25 primo volumio[3530]: info: handleBrowseUri took 1221 milliseconds Dec 16 17:29:25 primo volumio[3530]: info: Preload queue cleared Dec 16 17:29:26 primo volumio[3530]: info: Executing endpoint getSimilarArtists Dec 16 17:29:26 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 16 17:29:26 primo volumio[3530]: info: Executing endpoint metavolumio Dec 16 17:29:26 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 17:29:26 primo volumio[3530]: info: BOOT COMPLETED Dec 16 17:29:28 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:28 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:28 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:28 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 16 17:29:28 primo volumio[3530]: info: Not Reporting Auto name since its the default one Dec 16 17:29:28 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 16 17:29:28 primo volumio[3530]: No protocol specified Dec 16 17:29:28 primo volumio[3530]: xcb_connection_has_error() returned true Dec 16 17:29:28 primo sudo[4516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Dec 16 17:29:28 primo sudo[4516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:28 primo sudo[4516]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:28 primo sudo[4518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 16 17:29:28 primo sudo[4518]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 17:29:28 primo systemd[1]: Started RAAT DAEMON. Dec 16 17:29:28 primo sudo[4518]: pam_unix(sudo:session): session closed for user root Dec 16 17:29:28 primo volumio[3530]: info: Raat Daemon started successfully Dec 16 17:29:28 primo kernel: aml_tdm_open Dec 16 17:29:28 primo kernel: Not init audio effects Dec 16 17:29:28 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 16 17:29:28 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 16 17:29:28 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 16 17:29:28 primo kernel: aml_tdm_open Dec 16 17:29:28 primo kernel: Not init audio effects Dec 16 17:29:28 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Dec 16 17:29:28 primo kernel: tdm playback mute: 1, lane_cnt = 8 Dec 16 17:29:28 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Dec 16 17:29:29 primo volumio[3530]: info: Executing endpoint restartRAATSocket Dec 16 17:29:29 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 16 17:29:29 primo volumio[3530]: info: CorePlayQueue::getTrack 0 Dec 16 17:29:29 primo volumio[3530]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 17:29:30 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 17:29:31 primo volumio[3530]: info: RAAT: Requesting Headphone Status Dec 16 17:29:31 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Dec 16 17:29:31 primo volumio[3530]: info: MCU Signalled Headphone Mode Disabled Dec 16 17:29:31 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Dec 16 17:29:31 primo volumio[3530]: info: Reporting Headphone State: false Dec 16 17:29:31 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:31 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 17:29:31 primo volumio[3530]: info: Updating RAAT Signal Path Dec 16 17:29:53 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 16 17:29:53 primo volumio[3530]: info: handleBrowseUri took 502 milliseconds Dec 16 17:29:53 primo volumio[3530]: info: Preload queue cleared Dec 16 17:29:54 primo volumio[3530]: info: Executing endpoint getSimilarArtists Dec 16 17:29:54 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 16 17:29:54 primo volumio[3530]: info: Executing endpoint metavolumio Dec 16 17:29:54 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 17:30:10 primo volumio[3530]: info: Adding qobuz://artist/3097902 to favourites with specific qobuz method Dec 16 17:30:12 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 16 17:30:13 primo volumio[3530]: info: handleBrowseUri took 672 milliseconds Dec 16 17:30:13 primo volumio[3530]: info: Preload queue cleared Dec 16 17:30:13 primo volumio[3530]: info: Executing endpoint getSimilarArtists Dec 16 17:30:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Dec 16 17:30:13 primo volumio[3530]: info: Executing endpoint metavolumio Dec 16 17:30:13 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 16 17:30:17 primo volumio[3530]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Dec 16 17:30:18 primo volumio[3530]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 17:30:18 primo volumio[3530]: TypeError: Cannot read property 'slice' of null Dec 16 17:30:18 primo volumio[3530]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Dec 16 17:30:18 primo volumio[3530]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Dec 16 17:30:18 primo volumio[3530]: at Object.onceWrapper (events.js:520:26) Dec 16 17:30:18 primo volumio[3530]: at ClientRequest.emit (events.js:400:28) Dec 16 17:30:18 primo volumio[3530]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Dec 16 17:30:18 primo volumio[3530]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Dec 16 17:30:18 primo volumio[3530]: at TLSSocket.socketOnData (_http_client.js:515:22) Dec 16 17:30:18 primo volumio[3530]: at TLSSocket.emit (events.js:400:28) Dec 16 17:30:18 primo volumio[3530]: at addChunk (internal/streams/readable.js:293:12) Dec 16 17:30:18 primo volumio[3530]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 16 17:30:18 primo volumio[3530]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Dec 16 17:30:18 primo volumio[3530]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Dec 16 17:30:18 primo volumio[3530]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 17:30:18 primo sudo[4731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 17:29 Dec 16 17:30:18 primo sudo[4731]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 08:41:01 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="4dc90e4f98d685221d79f0e0b580095e"