-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Tue 2026-04-14 13:23:03 HKT. -- Apr 14 13:22:00 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:00 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:00 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:00 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:00 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:01 blue-cat sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 13:22:01 blue-cat sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:01 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:01 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:01 blue-cat sudo[2584]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:01 blue-cat sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 13:22:01 blue-cat sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:01 blue-cat sudo[2586]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:01 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.206 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:01 blue-cat sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:01 blue-cat sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:01 blue-cat systemd[1]: Started UPnP Renderer front-end to MPD. Apr 14 13:22:01 blue-cat sudo[2590]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:01 blue-cat volumio[940]: info: Upmpdcli Daemon Started Apr 14 13:22:01 blue-cat volumio[2592]: writing RSA key Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:01 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:01 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:01 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:01 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:01 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.206 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:01 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:02 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:02 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:02 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:02 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:02 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:02 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.206 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:03 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:03 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.206 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:03 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:03 blue-cat sudo[2641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 13:22:03 blue-cat sudo[2641]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:03 blue-cat sudo[2641]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:03 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 13:22:03 blue-cat sudo[2643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 13:22:03 blue-cat sudo[2643]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:03 blue-cat sudo[2643]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150 from 192.168.31.250 UA: Mozilla/5.0 (Linux; Android 15; NX721J Build/AQ3A.240812.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 13:22:03 blue-cat sudo[2647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 13:22:03 blue-cat sudo[2647]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:03 blue-cat sudo[2647]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:03 blue-cat sudo[2650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 13:22:03 blue-cat sudo[2650]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:03 blue-cat sudo[2650]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:03 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150 from 192.168.31.250 UA: Mozilla/5.0 (Linux; Android 15; NX721J Build/AQ3A.240812.002; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.177 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 14 13:22:03 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:03 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: Listing playlists Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:03 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:03 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:03 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:03 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.206 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:03 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:04 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:04 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:04 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:04 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:04 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:22:05 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:05 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:05 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:05 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:22:12 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:12 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:12 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 14 13:22:12 blue-cat volumio[940]: info: MyVolumio login type: Token Apr 14 13:22:12 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:22:12 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:12.917Z level=INFO msg="emitting user changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" userId= Apr 14 13:22:12 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:12.918Z level=INFO msg="emitting user changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" userId= Apr 14 13:22:13 blue-cat volumio[940]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 14 13:22:13 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 13:22:13 blue-cat volumio[940]: info: MyVolumio token set successfully Apr 14 13:22:13 blue-cat volumio[940]: info: MYVOLUMIO: Adding device Apr 14 13:22:13 blue-cat volumio[940]: info: MYVOLUMIO: Evaluating Server Apr 14 13:22:14 blue-cat volumio[940]: info: MyVolumio Plan changed: premium Apr 14 13:22:14 blue-cat volumio[940]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 14 13:22:14 blue-cat volumio[940]: info: Removing browser output: myVolumio user plan is not superstar Apr 14 13:22:14 blue-cat volumio[940]: info: Removing audio output: Apr 14 13:22:14 blue-cat volumio[940]: info: MYVOLUMIO: Adding device Apr 14 13:22:14 blue-cat volumio[940]: info: MYVOLUMIO: Evaluating Server Apr 14 13:22:14 blue-cat volumio[940]: info: Remote config written successfully Apr 14 13:22:14 blue-cat volumio[940]: info: Starting Tunnel 1 Apr 14 13:22:14 blue-cat volumio[940]: info: Starting Tunnel Connection Checker Apr 14 13:22:14 blue-cat volumio[940]: info: Setting Geolocation for MyVolumio to as1 Apr 14 13:22:14 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:14 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:14 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:14 blue-cat volumio[940]: info: MYVolumio Device enabled Apr 14 13:22:14 blue-cat volumio[940]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 14 13:22:14 blue-cat volumio[940]: info: MyVolumio status changed Apr 14 13:22:14 blue-cat volumio[940]: info: Streaming services startup Apr 14 13:22:14 blue-cat volumio[940]: info: Starting Streaming Daemon Apr 14 13:22:14 blue-cat volumio[940]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Apr 14 13:22:14 blue-cat sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 14 13:22:14 blue-cat sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:14 blue-cat sudo[2705]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:14 blue-cat volumio[940]: error: Cannot start Volumio Streaming Daemon Apr 14 13:22:14 blue-cat volumio[940]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 14 13:22:14 blue-cat volumio[940]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 14 13:22:15 blue-cat volumio[940]: info: Setting Geolocation for MyVolumio to as1 Apr 14 13:22:15 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:15 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:15 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:15 blue-cat volumio[940]: info: Successfully Added MyVolumio device Apr 14 13:22:15 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 14 13:22:15 blue-cat volumio[940]: info: [1776144135495] Starting BluetoothController Apr 14 13:22:15 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 14 13:22:16 blue-cat sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 14 13:22:16 blue-cat sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:16 blue-cat sudo[2708]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:16 blue-cat volumio[940]: info: MRS: MultiRoom plugin initialized Apr 14 13:22:16 blue-cat volumio[940]: info: MRS: STOPPING SNAPCLIENT Apr 14 13:22:16 blue-cat volumio[940]: info: MRS: Snap server stop Apr 14 13:22:16 blue-cat sudo[2724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 14 13:22:16 blue-cat sudo[2724]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:16 blue-cat volumio[940]: info: MRS: STOPPING volumioStreaming Apr 14 13:22:16 blue-cat sudo[2727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 14 13:22:16 blue-cat sudo[2727]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:16 blue-cat sudo[2724]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:16 blue-cat sudo[2730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 14 13:22:16 blue-cat sudo[2730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:16 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 14 13:22:16 blue-cat sudo[2727]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:16 blue-cat sudo[2733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 14 13:22:16 blue-cat sudo[2733]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:16 blue-cat sudo[2733]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:16 blue-cat sudo[2730]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:16 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 14 13:22:16 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Apr 14 13:22:17 blue-cat volumio[940]: info: Preparing to generate the ALSA configuration file Apr 14 13:22:17 blue-cat volumio[940]: info: Updating MyVolumio device info Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: Updating MyVolumio device info Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 14 13:22:17 blue-cat volumio[940]: info: Successfully Added MyVolumio device Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:22:17 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:17 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:17 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 13:22:17 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:17 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:17 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: Removed streaming files Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: volumioStreaming STOPPED Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: SNAPSERVER STOPPED Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: SNAPCLIENT STOPPED Apr 14 13:22:17 blue-cat volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 14 13:22:17 blue-cat volumio[940]: info: Output device has changed, restarting MPD Apr 14 13:22:17 blue-cat sudo[2736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 13:22:17 blue-cat sudo[2736]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat sudo[2736]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 13:22:17 blue-cat sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat systemd[1]: Stopping Music Player Daemon... Apr 14 13:22:17 blue-cat volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output for this device Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:17 blue-cat volumio[940]: info: Adding audio output: Apr 14 13:22:17 blue-cat volumio[940]: info: Adding audio output: Apr 14 13:22:17 blue-cat sudo[2747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Blue Cat Apr 14 13:22:17 blue-cat sudo[2747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat sudo[2747]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 14 13:22:17 blue-cat volumio[940]: info: Adding METAVOLUMIO REST API Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 14 13:22:17 blue-cat volumio[940]: info: Preparing CD Folders Apr 14 13:22:17 blue-cat volumio[940]: info: Adding CD REST API Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 14 13:22:17 blue-cat volumio[940]: info: Starting UDEV Watcher for CD Apr 14 13:22:17 blue-cat volumio[940]: info: Detecting CD presence with UDEV Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 14 13:22:17 blue-cat systemd[1]: mpd.service: Succeeded. Apr 14 13:22:17 blue-cat systemd[1]: Stopped Music Player Daemon. Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 14 13:22:17 blue-cat volumio[940]: info: Adding inputs REST Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 14 13:22:17 blue-cat volumio[940]: info: Scanning Audio Inputs Apr 14 13:22:17 blue-cat systemd[1]: Starting Music Player Daemon... Apr 14 13:22:17 blue-cat volumio[940]: info: Checking against Known Cards name Apr 14 13:22:17 blue-cat volumio[940]: info: Adding Server instance for streaming Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 14 13:22:17 blue-cat volumio[940]: error: Hi Res Audio Failed Login: Missing Login Data Apr 14 13:22:17 blue-cat volumio[940]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 14 13:22:17 blue-cat volumio[940]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 14 13:22:17 blue-cat volumio[940]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Apr 14 13:22:17 blue-cat volumio[940]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Apr 14 13:22:17 blue-cat sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 14 13:22:17 blue-cat sudo[2752]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 13:22:17 blue-cat sudo[2752]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat sudo[2752]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat volumio[940]: info: QobuzConnect: Starting Qobuz Connect socket and service Apr 14 13:22:17 blue-cat sudo[2756]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat sudo[2764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 13:22:17 blue-cat sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat volumio[940]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Apr 14 13:22:17 blue-cat volumio[940]: info: Adding TIDAL REST API Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 14 13:22:17 blue-cat volumio[940]: info: Stopping AccessToken refresher cron for QOBUZ Apr 14 13:22:17 blue-cat sudo[2764]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat sudo[2767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 13:22:17 blue-cat sudo[2767]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat volumio[940]: info: AccessToken refresher cron started for QOBUZ Apr 14 13:22:17 blue-cat volumio[940]: info: Adding QOBUZ REST API Endpoints Apr 14 13:22:17 blue-cat volumio[940]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 14 13:22:17 blue-cat volumio[940]: info: Bluetooth name changed to Blue Cat Apr 14 13:22:17 blue-cat volumio[940]: info: MPD Permissions set Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:17 blue-cat volumio[940]: info: Starting Shairport Sync Apr 14 13:22:17 blue-cat systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 13:22:17 blue-cat sudo[2767]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:17 blue-cat volumio[940]: info: Preparing to generate the ALSA configuration file Apr 14 13:22:17 blue-cat sudo[2775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 13:22:17 blue-cat sudo[2775]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:17 blue-cat systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 13:22:17 blue-cat systemd[1]: shairport-sync.service: Succeeded. Apr 14 13:22:17 blue-cat systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:17 blue-cat volumio[940]: info: Executing endpoint qc_getconfig Apr 14 13:22:17 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 13:22:18 blue-cat systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:18 blue-cat sudo[2775]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.026 [2769.2769] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 13:22:18 blue-cat volumio[940]: info: Shairport-Sync Started Apr 14 13:22:18 blue-cat volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 14 13:22:18 blue-cat volumio[940]: info: Output device has changed, restarting MPD Apr 14 13:22:18 blue-cat volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:18 blue-cat sudo[2784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 13:22:18 blue-cat sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.138 [2769.2769] INFO VolumeManager: [0x160e2f8]: Setting new playback volume: 75 Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.138 [2769.2769] INFO VolumeManager: [0x160e2f8]: Setting new mute state: 0 Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.138 [2769.2769] INFO AudioStreamManager: [0x160e050]: Setting new audio download buffer size: 1048576 Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.138 [2769.2769] INFO QobuzConnect: [0x160ebc0]: Client initialized! Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.138 [2769.2769] INFO SampleApp: Starting Avahi advertising, name: Blue Cat, service name: _qobuz-connect._tcp Apr 14 13:22:18 blue-cat sudo[2784]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat sudo[2786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.153 [2769.2769] INFO LocalConfigManager: [0x160dd78]: Starting Local Configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.153 [2769.2769] INFO SampleApp: Starting Local configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.154 [2769.2769] INFO SampleApp: Connected to UNIX socket client 0x1603f60 Apr 14 13:22:18 blue-cat sudo[2786]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.175 [2769.2769] INFO SampleApp: Playback volume changed: 75 Apr 14 13:22:18 blue-cat volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 13:22:18 blue-cat volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 13:22:18 blue-cat systemd[1]: mpd.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Music Player Daemon. Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: setDeactiveState invoked Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:18 blue-cat volumio[940]: info: Preparing to generate the ALSA configuration file Apr 14 13:22:18 blue-cat systemd[1]: Starting Music Player Daemon... Apr 14 13:22:18 blue-cat volumio[940]: info: Successfully Updated MyVolumio device Apr 14 13:22:18 blue-cat sudo[2800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:18 blue-cat volumio[940]: info: MPD Permissions set Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat sudo[2800]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 13:22:18 blue-cat sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: Asound.conf file unchanged, so no further update is needed Apr 14 13:22:18 blue-cat volumio[940]: info: Output device has changed, restarting MPD Apr 14 13:22:18 blue-cat sudo[2793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 13:22:18 blue-cat sudo[2793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.332 [2769.2769] INFO SampleApp: Stopping Local configuration server Apr 14 13:22:18 blue-cat sudo[2793]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat qobuz-connect[2769]: 20260414 13:22:18.343 [2769.2769] INFO SampleApp: shat down connection on UNIX socket Apr 14 13:22:18 blue-cat systemd[1]: qobuz-connect.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 13:22:18 blue-cat systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 13:22:18 blue-cat sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 14 13:22:18 blue-cat sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat sudo[2811]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat sudo[2802]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat volumio[940]: info: Output device has changed, restarting Shairport Sync Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:18 blue-cat sudo[2815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 14 13:22:18 blue-cat sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 14 13:22:18 blue-cat systemd[1]: mpd.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Music Player Daemon. Apr 14 13:22:18 blue-cat volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 14 13:22:18 blue-cat volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: setDeactiveState invoked Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:18 blue-cat systemd[1]: Starting Music Player Daemon... Apr 14 13:22:18 blue-cat volumio[940]: info: Successfully Updated MyVolumio device Apr 14 13:22:18 blue-cat sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 14 13:22:18 blue-cat volumio[940]: info: MPD Permissions set Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: setDeactiveState invoked Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:18 blue-cat sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat volumio[940]: info: Executing endpoint qc_getconfig Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 13:22:18 blue-cat sudo[2828]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.528 [2812.2812] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 13:22:18 blue-cat volumio[940]: info: Starting Shairport Sync Apr 14 13:22:18 blue-cat sudo[2821]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 14 13:22:18 blue-cat sudo[2821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat sudo[2832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.534 [2812.2812] INFO VolumeManager: [0x1c422f8]: Setting new playback volume: 75 Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.534 [2812.2812] INFO VolumeManager: [0x1c422f8]: Setting new mute state: 0 Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.534 [2812.2812] INFO AudioStreamManager: [0x1c42050]: Setting new audio download buffer size: 1048576 Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.534 [2812.2812] INFO QobuzConnect: [0x1c42bc0]: Client initialized! Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.534 [2812.2812] INFO SampleApp: Starting Avahi advertising, name: Blue Cat, service name: _qobuz-connect._tcp Apr 14 13:22:18 blue-cat sudo[2832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat sudo[2821]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.545 [2812.2812] INFO LocalConfigManager: [0x1c41d78]: Starting Local Configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.545 [2812.2812] INFO SampleApp: Starting Local configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.546 [2812.2812] INFO SampleApp: Connected to UNIX socket client 0x1c37f60 Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 13:22:18 blue-cat sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat systemd[1]: Stopping Volumio Qobuz Connect Service... Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.583 [2812.2812] INFO SampleApp: Stopping Local configuration server Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 14 13:22:18 blue-cat systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 13:22:18 blue-cat systemd[1]: shairport-sync.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:18 blue-cat qobuz-connect[2812]: 20260414 13:22:18.594 [2812.2812] INFO SampleApp: shat down connection on UNIX socket Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: setDeactiveState invoked Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:18 blue-cat systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:18 blue-cat systemd[1]: qobuz-connect.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Volumio Qobuz Connect Service. Apr 14 13:22:18 blue-cat volumio[940]: info: Starting Shairport Sync Apr 14 13:22:18 blue-cat systemd[1]: Started Volumio Qobuz Connect Service. Apr 14 13:22:18 blue-cat sudo[2832]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat sudo[2838]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat sudo[2848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 14 13:22:18 blue-cat sudo[2848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:18 blue-cat volumio[940]: info: Shairport-Sync Started Apr 14 13:22:18 blue-cat systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 14 13:22:18 blue-cat systemd[1]: shairport-sync.service: Succeeded. Apr 14 13:22:18 blue-cat systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:18 blue-cat volumio[940]: info: Executing endpoint qc_getconfig Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 14 13:22:18 blue-cat systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 14 13:22:18 blue-cat sudo[2848]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.730 [2845.2845] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.735 [2845.2845] INFO VolumeManager: [0x12732f8]: Setting new playback volume: 75 Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.735 [2845.2845] INFO VolumeManager: [0x12732f8]: Setting new mute state: 0 Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.735 [2845.2845] INFO AudioStreamManager: [0x1273050]: Setting new audio download buffer size: 1048576 Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.735 [2845.2845] INFO QobuzConnect: [0x1273bc0]: Client initialized! Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.735 [2845.2845] INFO SampleApp: Starting Avahi advertising, name: Blue Cat, service name: _qobuz-connect._tcp Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 14 13:22:18 blue-cat volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 14 13:22:18 blue-cat volumio[940]: info: Shairport-Sync Started Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.751 [2845.2845] INFO LocalConfigManager: [0x1272d78]: Starting Local Configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.751 [2845.2845] INFO SampleApp: Starting Local configuration server Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.751 [2845.2845] INFO SampleApp: Connected to UNIX socket client 0x1268f60 Apr 14 13:22:18 blue-cat qobuz-connect[2845]: 20260414 13:22:18.881 [2845.2845] INFO SampleApp: Playback volume changed: 75 Apr 14 13:22:18 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:18 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:19 blue-cat mpd[2836]: Apr 14 13:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 14 13:22:19 blue-cat systemd[1]: Started Music Player Daemon. Apr 14 13:22:19 blue-cat sudo[2815]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:19 blue-cat sudo[2786]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:19 blue-cat sudo[2739]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:19 blue-cat volumio[940]: error: MPD error: The expression evaluated to a falsy value: Apr 14 13:22:19 blue-cat volumio[940]: assert.ok(self.idling) Apr 14 13:22:19 blue-cat volumio[940]: error: The expression evaluated to a falsy value: Apr 14 13:22:19 blue-cat volumio[940]: assert.ok(self.idling) Apr 14 13:22:19 blue-cat volumio[940]: error: MPD error: The expression evaluated to a falsy value: Apr 14 13:22:19 blue-cat volumio[940]: assert.ok(self.idling) Apr 14 13:22:19 blue-cat volumio[940]: error: The expression evaluated to a falsy value: Apr 14 13:22:19 blue-cat volumio[940]: assert.ok(self.idling) Apr 14 13:22:19 blue-cat volumio[940]: error: updateQueue error: null Apr 14 13:22:19 blue-cat sudo[2865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 14 13:22:19 blue-cat sudo[2865]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:19 blue-cat systemd[1]: Started MyVolumio SSH Tunnel. Apr 14 13:22:19 blue-cat sudo[2865]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:19 blue-cat volumio[940]: info: Remote SSH Started Apr 14 13:22:19 blue-cat autossh[2868]: port set to 0, monitoring disabled Apr 14 13:22:19 blue-cat autossh[2868]: starting ssh (count 1) Apr 14 13:22:19 blue-cat autossh[2868]: ssh child pid is 2871 Apr 14 13:22:19 blue-cat volumio[940]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:19 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:19 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:19 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:19 blue-cat volumiossh-tunnel[2867]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Apr 14 13:22:19 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:19 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:19 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:19 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:19 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:20 blue-cat sudo[2873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 14 13:22:20 blue-cat sudo[2873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Apr 14 13:22:20 blue-cat systemd[1]: Started Volumio Bluetooth Module. Apr 14 13:22:20 blue-cat sudo[2873]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:20 blue-cat volumio[940]: info: Volumio BT Module successfully started Apr 14 13:22:20 blue-cat sudo[2876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 14 13:22:20 blue-cat sudo[2876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:20 blue-cat volumiobt[2875]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 14 13:22:20 blue-cat sudo[2876]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:20 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:20 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:20 blue-cat volumio[940]: info: TidalConnect service stoped! Apr 14 13:22:20 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:20 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:20 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:20 blue-cat volumio[940]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 14 13:22:20 blue-cat volumio[940]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:20 blue-cat sudo[2888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 14 13:22:20 blue-cat sudo[2888]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:20 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:20 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:20 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:20 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:20 blue-cat systemd[1]: Started Volumio Tidal Connect Service. Apr 14 13:22:20 blue-cat sudo[2888]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:21 blue-cat volumio[940]: info: Executing endpoint tc_getconfig Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 14 13:22:21 blue-cat vtcs[2890]: STARTING TidalConnect services, version: 1.6.1 Apr 14 13:22:21 blue-cat vtcs[2890]: STARTED TidalConnect services. Apr 14 13:22:21 blue-cat volumio[940]: info: Executing endpoint tc_connect Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 14 13:22:21 blue-cat volumio[940]: info: Connecting to TidalConnect Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: 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} Apr 14 13:22:21 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: No code Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: 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} Apr 14 13:22:21 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService stop Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: No code Apr 14 13:22:21 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:22:21 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.109Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.110Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.110Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.110Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.113Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.113Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.113Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.113Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.114Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.115Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.115Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.115Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.116Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.116Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.116Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.117Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.118Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.118Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.118Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.118Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.120Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.120Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_STOPPED positionMs=0 volume=86 Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.120Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id= title= Apr 14 13:22:21 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:22:21.120Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id= title= Apr 14 13:22:21 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:21 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:21 blue-cat sudo[2909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 14 13:22:21 blue-cat sudo[2909]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 13:22:21 blue-cat volumiobt[2875]: pulseaudio: no process found Apr 14 13:22:21 blue-cat sudo[2909]: pam_unix(sudo:session): session closed for user root Apr 14 13:22:21 blue-cat dbus-daemon[648]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.62' (uid=1000 pid=2913 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 14 13:22:21 blue-cat systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 14 13:22:21 blue-cat volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 13:22:21 blue-cat dbus-daemon[648]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 14 13:22:21 blue-cat systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Successfully called chroot. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Successfully dropped privileges. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Successfully limited resources. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Running. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Canary thread running. Apr 14 13:22:21 blue-cat rtkit-daemon[2914]: Watchdog thread running. Apr 14 13:22:21 blue-cat dbus-daemon[648]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.63' (uid=0 pid=2914 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 14 13:22:21 blue-cat systemd[1]: Starting Authorization Manager... Apr 14 13:22:21 blue-cat volumio[940]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 14 13:22:21 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:21 blue-cat polkitd[2918]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 14 13:22:21 blue-cat dbus-daemon[648]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 14 13:22:21 blue-cat systemd[1]: Started Authorization Manager. Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:21 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:21 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:21 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:21 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:22 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:22 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:22 blue-cat pulseaudio[2913]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Apr 14 13:22:22 blue-cat pulseaudio[2913]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Apr 14 13:22:22 blue-cat pulseaudio[2913]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Apr 14 13:22:22 blue-cat pulseaudio[2913]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Apr 14 13:22:22 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:22 blue-cat pulseaudio[2913]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 14 13:22:22 blue-cat pulseaudio[2913]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 14 13:22:22 blue-cat bluetoothd[771]: Endpoint registered: sender=:1.83 path=/MediaEndpoint/A2DPSource Apr 14 13:22:22 blue-cat bluetoothd[771]: Endpoint registered: sender=:1.83 path=/MediaEndpoint/A2DPSink Apr 14 13:22:22 blue-cat volumiobt[2875]: Applying permissions Apr 14 13:22:22 blue-cat volumiobt[2875]: Setting BT discoverable and pairable Apr 14 13:22:22 blue-cat pulseaudio[2913]: 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 Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:22 blue-cat kernel: Bluetooth: RFCOMM TTY layer initialized Apr 14 13:22:22 blue-cat kernel: Bluetooth: RFCOMM socket layer initialized Apr 14 13:22:22 blue-cat kernel: Bluetooth: RFCOMM ver 1.11 Apr 14 13:22:22 blue-cat volumiobt[2875]: [118B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [82B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [82B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [83B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [38B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [84B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [100B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [60B blob data] Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> -e power on Apr 14 13:22:22 blue-cat volumiobt[2875]: Invalid command in menu main: -e Apr 14 13:22:22 blue-cat volumiobt[2875]: Apr 14 13:22:22 blue-cat volumiobt[2875]: Use "help" for a list of available commands in a menu. Apr 14 13:22:22 blue-cat volumiobt[2875]: Use "menu " if you want to enter any submenu. Apr 14 13:22:22 blue-cat volumiobt[2875]: Use "back" if you want to return to menu main. Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> agent on Apr 14 13:22:22 blue-cat volumiobt[2875]: Agent is already registered Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> discoverable on Apr 14 13:22:22 blue-cat volumiobt[2875]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> pairable on Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> agent NoInputNoOutput Apr 14 13:22:22 blue-cat volumiobt[2875]: Agent is already registered Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> default-agent Apr 14 13:22:22 blue-cat volumiobt[2875]: [4A-FC-03-95-FB-AC]> quit Apr 14 13:22:22 blue-cat volumiobt[2875]: [75B blob data] Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 13:22:22 blue-cat volumio[940]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 13:22:22 blue-cat volumio[940]: info: Received Get System Version Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 13:22:22 blue-cat volumio[940]: info: Received Get System Info Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:22 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:22 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:22 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:22 blue-cat volumio[940]: info: MRS: Getting audio outputs on start Apr 14 13:22:22 blue-cat volumio[940]: info: MRS: Requesting all other devices output Apr 14 13:22:23 blue-cat volumio[940]: info: TidalConnect service started! Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:24 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:24 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:24 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:24 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:24 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:24 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:24 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:24 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:24 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:24 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:25 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:25 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:25 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:25 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:25 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:25 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:25 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:25 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:25 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:25 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:26 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:26 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:26 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:26 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:26 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:26 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:26 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:26 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.250 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:26 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:26 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:26 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:27 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:27 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:27 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:27 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.150:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 13:22:27 blue-cat volumio[940]: info: Discovery: Getting this device information Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:22:27 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 13:22:27 blue-cat volumio[940]: verbose: New Socket.io Connection to 192.168.31.151:3000 from 192.168.31.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 13:22:27 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 13:22:28 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetQueue Apr 14 13:22:28 blue-cat volumio[940]: info: CoreStateMachine::getQueue Apr 14 13:22:28 blue-cat volumio[940]: info: CorePlayQueue::getQueue Apr 14 13:22:44 blue-cat kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Apr 14 13:22:49 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 13:22:49 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 13:22:49 blue-cat volumio[940]: info: Preload queue cleared Apr 14 13:22:55 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 13:22:56 blue-cat volumio[940]: info: Preload queue cleared Apr 14 13:22:59 blue-cat volumio[940]: info: Preload queue cleared Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::ClearQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::stop Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::clearPlayQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::saveQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::addQueueItems Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::addQueueItems Apr 14 13:22:59 blue-cat volumio[940]: info: Preload queue cleared Apr 14 13:22:59 blue-cat volumio[940]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::saveQueue Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::updateTrackBlock Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::getTrackBlock Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::volumioPlay Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::play index 0 Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::stop Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::play index undefined Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:59 blue-cat volumio[940]: info: CoreStateMachine::startPlaybackTimer Apr 14 13:22:59 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetVisibleSources Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 14 13:22:59 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 14 13:22:59 blue-cat volumio[940]: info: [1776144179398] ControllerWebradio::clearAddPlayTrack Apr 14 13:22:59 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand stop Apr 14 13:22:59 blue-cat volumio[940]: info: sendMpdCommand stop took 4 milliseconds Apr 14 13:22:59 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand clear Apr 14 13:22:59 blue-cat volumio[940]: info: Apr 14 13:22:59 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:22:59 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:22:59 blue-cat volumio[940]: info: sendMpdCommand clear took 3 milliseconds Apr 14 13:22:59 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Apr 14 13:22:59 blue-cat volumio[940]: info: Apr 14 13:22:59 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:22:59 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:22:59 blue-cat volumio[940]: info: Apr 14 13:22:59 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:22:59 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:00 blue-cat volumio[940]: error: updateQueue error: null Apr 14 13:23:00 blue-cat volumio[940]: info: Apr 14 13:23:00 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:00 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 658ms Apr 14 13:23:00 blue-cat volumio[940]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 656 milliseconds Apr 14 13:23:00 blue-cat volumio[940]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 14 13:23:00 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand play Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 658ms Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 656ms Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 5ms Apr 14 13:23:00 blue-cat volumio[940]: info: Apr 14 13:23:00 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:00 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:00 blue-cat volumio[940]: info: Apr 14 13:23:00 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:00 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:00 blue-cat volumio[940]: info: sendMpdCommand play took 5 milliseconds Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 2ms Apr 14 13:23:00 blue-cat volumio[940]: info: ------------------------------ 1ms Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 9 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 11 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 10 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus stop Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 18ms Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 8 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 2 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 1 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.321Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.321Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.321Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.322Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.326Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.326Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.326Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.326Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.327Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.328Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.328Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.328Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.329Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.329Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.330Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.330Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 76ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 75ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 57 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 8314/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.388Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.388Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.389Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.390Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.391Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.392Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.392Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.393Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.394Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.394Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.395Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.395Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.396Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.396Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.397Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.397Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=0 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.397Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.397Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 139ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 134ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 134ms Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 5ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 3 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 4ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 3 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 7ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 6 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 5ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 4 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 3ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 3 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.629Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.630Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.630Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.630Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.631Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.632Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.632Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.632Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.634Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.634Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.634Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.634Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.639Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.639Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.640Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.640Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 70ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 67ms Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces system playlist update Apr 14 13:23:02 blue-cat volumio[940]: info: Ignoring MPD Status Update Apr 14 13:23:02 blue-cat volumio[940]: info: Apr 14 13:23:02 blue-cat volumio[940]: ---------------------------- MPD announces state update: player Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::getState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand status Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 60 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 2ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand status took 2 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseState Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.702Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.702Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.702Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.702Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.703Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.703Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.704Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.704Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.705Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.705Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.705Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.705Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.706Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.707Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.707Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.707Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.708Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.709Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.709Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.710Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.710Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.711Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=301 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.711Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.711Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 142ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 140ms Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 140ms Apr 14 13:23:02 blue-cat volumio[940]: info: sendMpdCommand playlistinfo took 119 milliseconds Apr 14 13:23:02 blue-cat volumio[940]: verbose: ControllerMpd::parseTrackInfo Apr 14 13:23:02 blue-cat volumio[940]: info: ControllerMpd::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::servicePushState Apr 14 13:23:02 blue-cat volumio[940]: info: CorePlayQueue::getTrack 0 Apr 14 13:23:02 blue-cat volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Apr 14 13:23:02 blue-cat volumio[940]: verbose: CURRENT POSITION 0 Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState stateService play Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::syncState currentStatus play Apr 14 13:23:02 blue-cat volumio[940]: info: Received an update from plugin. extracting info from payload Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreStateMachine::pushState Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioPushState Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output update for this device Apr 14 13:23:02 blue-cat volumio[940]: info: MRS: Pushing multiroomSync output Apr 14 13:23:02 blue-cat volumio[940]: info: CoreCommandRouter::volumioGetState Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.796Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=624 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.796Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=624 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.796Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.797Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" state=STATUS_PLAYING positionMs=624 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.798Z level=INFO msg="emitting player state changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" state=STATUS_PLAYING positionMs=624 volume=86 Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.799Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.206:39648,192.168.31.206:35212 @ 0x32ec030" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio5-onboarding[1375]: time=2026-04-14T05:23:02.799Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.31.250:54528,00:00:00:00:00:00%01,192.168.31.250:57368 @ 0x31b5230" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Apr 14 13:23:02 blue-cat volumio[940]: info: ------------------------------ 153ms Apr 14 13:23:03 blue-cat volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 13:23:03 blue-cat volumio[940]: TypeError: Cannot read property 'slice' of null Apr 14 13:23:03 blue-cat volumio[940]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Apr 14 13:23:03 blue-cat volumio[940]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Apr 14 13:23:03 blue-cat volumio[940]: at Object.onceWrapper (events.js:422:26) Apr 14 13:23:03 blue-cat volumio[940]: at ClientRequest.emit (events.js:315:20) Apr 14 13:23:03 blue-cat volumio[940]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Apr 14 13:23:03 blue-cat volumio[940]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Apr 14 13:23:03 blue-cat volumio[940]: at TLSSocket.socketOnData (_http_client.js:509:22) Apr 14 13:23:03 blue-cat volumio[940]: at TLSSocket.emit (events.js:315:20) Apr 14 13:23:03 blue-cat volumio[940]: at addChunk (internal/streams/readable.js:309:12) Apr 14 13:23:03 blue-cat volumio[940]: at readableAddChunk (internal/streams/readable.js:284:9) Apr 14 13:23:03 blue-cat volumio[940]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Apr 14 13:23:03 blue-cat volumio[940]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Apr 14 13:23:03 blue-cat volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 13:23:03 blue-cat sudo[3055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 13:22 Apr 14 13:23:03 blue-cat sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"