-- Logs begin at Wed 2025-07-30 22:38:18 BST, end at Sun 2025-09-14 22:59:05 BST. -- Sep 14 22:58:32 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:32 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:32 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:32 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:32 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:32 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:33 volumio-office volumio[994]: verbose: New Socket.io Connection to 192.168.1.199:3000 from 192.168.1.135 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Sep 14 22:58:33 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:33 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:33 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 22:58:33 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 22:58:34 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:34 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:35 volumio-office sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 22:58:35 volumio-office sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 22:58:35 volumio-office sudo[1622]: pam_unix(sudo:session): session closed for user root Sep 14 22:58:35 volumio-office sudo[1624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 22:58:35 volumio-office sudo[1624]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 22:58:35 volumio-office sudo[1624]: pam_unix(sudo:session): session closed for user root Sep 14 22:58:35 volumio-office volumio[994]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.135 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 14 22:58:35 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 22:58:36 volumio-office sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 22:58:36 volumio-office sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 22:58:36 volumio-office sudo[1628]: pam_unix(sudo:session): session closed for user root Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 22:58:36 volumio-office sudo[1631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 22:58:36 volumio-office sudo[1631]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 22:58:36 volumio-office sudo[1631]: pam_unix(sudo:session): session closed for user root Sep 14 22:58:36 volumio-office volumio[994]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.135 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 7 Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:36 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 14 22:58:36 volumio-office volumio[994]: info: CoreStateMachine::getQueue Sep 14 22:58:36 volumio-office volumio[994]: info: CorePlayQueue::getQueue Sep 14 22:58:36 volumio-office volumio[994]: info: Listing playlists Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 22:58:36 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:36 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:36 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:36 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 22:58:36 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 22:58:37 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:37 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:37 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:37 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 14 22:58:38 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 22:58:40 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:40 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:40 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 22:58:40 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:40 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:40 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 22:58:40 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:40 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:40 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:40 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:43 volumio-office volumio[994]: Searching plugin music_service/volusonic Sep 14 22:58:43 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volusonic , search Sep 14 22:58:43 volumio-office volumio[994]: info: All search sources collected, pushing search results Sep 14 22:58:46 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 22:58:50 volumio-office volumio[994]: info: Preload queue cleared Sep 14 22:58:50 volumio-office volumio[994]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::ClearQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::stop Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::clearPlayQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::saveQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::addQueueItems Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::addQueueItems Sep 14 22:58:50 volumio-office volumio[994]: info: Preload queue cleared Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/XHjsb3ACDXMKd5TzOYg1Eq Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/XHjsb3ACDXMKd5TzOYg1Eq in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/u8jPQ7OLvrcU2BaUWyyOxQ Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/u8jPQ7OLvrcU2BaUWyyOxQ in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/kM3oNFvk2i5V32UNb6aGCu Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/kM3oNFvk2i5V32UNb6aGCu in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/4m6T8hzA79SEVV0Uv2sR3y Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/4m6T8hzA79SEVV0Uv2sR3y in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/hCVeV9FrgIFdTrmxSm1s57 Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/hCVeV9FrgIFdTrmxSm1s57 in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/CfB6jLawemF9ti5qfR7mks Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/CfB6jLawemF9ti5qfR7mks in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/AfVOnrELXstrejEOaNCPTu Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/AfVOnrELXstrejEOaNCPTu in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/uXDUrL0vcNTZCQf4Rotl23 Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/uXDUrL0vcNTZCQf4Rotl23 in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/z406fr8zTNYCPUQ3101Kdl Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/z406fr8zTNYCPUQ3101Kdl in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/ph6Pf55GMKUsKpEjLIcKZP Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/ph6Pf55GMKUsKpEjLIcKZP in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/l8SVkIMGD5iRyZMBpNEzEe Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/l8SVkIMGD5iRyZMBpNEzEe in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/XHjsb3ACDXMKd5TzOYg1Eq from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/hCVeV9FrgIFdTrmxSm1s57 from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/AfVOnrELXstrejEOaNCPTu from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/z406fr8zTNYCPUQ3101Kdl from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/uXDUrL0vcNTZCQf4Rotl23 from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/kM3oNFvk2i5V32UNb6aGCu from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/CfB6jLawemF9ti5qfR7mks from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::saveQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::getTrackBlock Sep 14 22:58:50 volumio-office volumio[994]: info: CoreCommandRouter::volumioPlay Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::play index 10 Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::addQueueItems Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::addQueueItems Sep 14 22:58:50 volumio-office volumio[994]: info: Preload queue cleared Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/ACwaGq4yFOzDmrCywkCgJT Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/ACwaGq4yFOzDmrCywkCgJT in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/2twmoVjeEV3Dy50dx7Il5Q Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/2twmoVjeEV3Dy50dx7Il5Q in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/OZLVPpFI2g6CUTRhmvBUe6 Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/OZLVPpFI2g6CUTRhmvBUe6 in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: Adding Item to queue: volusonic/track/W7jvik9ZZ5QAaKoW2wt1aR Sep 14 22:58:50 volumio-office volumio[994]: info: Exploding uri volusonic/track/W7jvik9ZZ5QAaKoW2wt1aR in service volusonic Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::stop Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::play index undefined Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::getTrack 10 Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/OZLVPpFI2g6CUTRhmvBUe6 from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: error: Commandrouter: Cannot explode uri volusonic/track/ACwaGq4yFOzDmrCywkCgJT from service volusonic: Error: explodeUri volusonic/track Sep 14 22:58:50 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::saveQueue Sep 14 22:58:50 volumio-office volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 14 22:58:50 volumio-office volumio[994]: info: CorePlayQueue::getTrackBlock Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 22:58:54 volumio-office volumio[994]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 14 22:58:54 volumio-office volumio[994]: info: Received Get System Version Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 22:58:54 volumio-office volumio[994]: info: Received Get System Info Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:54 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:54 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::volumioplayNextItems Sep 14 22:58:54 volumio-office volumio[994]: info: CoreStateMachine::playNextItems Sep 14 22:58:54 volumio-office volumio[994]: info: CorePlayQueue::addPlayNextItems Sep 14 22:58:54 volumio-office volumio[994]: info: Preload queue cleared Sep 14 22:58:54 volumio-office volumio[994]: info: Adding Item to play next: volusonic/track/l8SVkIMGD5iRyZMBpNEzEe Sep 14 22:58:54 volumio-office volumio[994]: info: Exploding uri volusonic/track/l8SVkIMGD5iRyZMBpNEzEe in service volusonic Sep 14 22:58:54 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushQueue Sep 14 22:58:54 volumio-office volumio[994]: info: CorePlayQueue::saveQueue Sep 14 22:58:54 volumio-office volumio[994]: info: CoreStateMachine::updateTrackBlock Sep 14 22:58:54 volumio-office volumio[994]: info: CorePlayQueue::getTrackBlock Sep 14 22:58:57 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 22:58:57 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 22:58:57 volumio-office volumio[994]: info: Discovery: Getting this device information Sep 14 22:58:57 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:57 volumio-office volumio[994]: info: CorePlayQueue::getTrack 0 Sep 14 22:58:57 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioPlay Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::play index 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::stop Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::play index undefined Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::startPlaybackTimer Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139266] ControllerVolusonic::clearAddPlayTrack Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand stop took 1 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand clear took 1 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic" Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic" Sep 14 22:58:59 volumio-office volumio[994]: error: updateQueue error: null Sep 14 22:58:59 volumio-office volumio[994]: error: updateQueue error: null Sep 14 22:58:59 volumio-office volumio[994]: error: updateQueue error: null Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 8ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 6ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 5ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand add "http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic" took 2 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand play Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 7ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand play took 5 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 4ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 3ms Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139287] ControllerVolusonic: MPD player state update Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 17 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 21 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 20 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 19 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 19 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139314] ControllerVolusonic::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic","trackType":""} Sep 14 22:58:59 volumio-office volumio[994]: verbose: CURRENT POSITION 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState stateService play Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState currentStatus stop Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 18 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 17 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 10 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 9 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139332] ControllerVolusonic::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic","trackType":""} Sep 14 22:58:59 volumio-office volumio[994]: verbose: CURRENT POSITION 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState stateService play Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState currentStatus play Sep 14 22:58:59 volumio-office volumio[994]: info: Received an update from plugin. extracting info from payload Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushState Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 61ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 59ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 58ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 23 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 51ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 42ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 41ms Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioGetQueue Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::getQueue Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getQueue Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139375] ControllerVolusonic: MPD player state update Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces system playlist update Sep 14 22:58:59 volumio-office volumio[994]: info: Ignoring MPD Status Update Sep 14 22:58:59 volumio-office volumio[994]: info: Sep 14 22:58:59 volumio-office volumio[994]: ---------------------------- MPD announces state update: player Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::getState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand status Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 12 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 13ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 12 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 6ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 5 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 4ms Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand status took 4 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseState Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: verbose: ControllerMpd::parseTrackInfo Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: [1757887139396] ControllerVolusonic::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":232,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"884 Kbps","isStreaming":false,"title":"Sorry, You're Not A Winner","artist":"Enter Shikari","album":"Take To The Skies","uri":"http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic","trackType":""} Sep 14 22:58:59 volumio-office volumio[994]: verbose: CURRENT POSITION 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState stateService play Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::syncState currentStatus play Sep 14 22:58:59 volumio-office volumio[994]: info: Received an update from plugin. extracting info from payload Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreStateMachine::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::volumioPushState Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ControllerMpd::pushState Sep 14 22:58:59 volumio-office volumio[994]: info: CoreCommandRouter::servicePushState Sep 14 22:58:59 volumio-office volumio[994]: info: CorePlayQueue::getTrack 1 Sep 14 22:58:59 volumio-office volumio[994]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 41ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 33ms Sep 14 22:58:59 volumio-office volumio[994]: info: ------------------------------ 31ms Sep 14 22:59:04 volumio-office volumio[994]: info: Adding http://192.168.1.155:4533/rest/stream.view?id=l8SVkIMGD5iRyZMBpNEzEe&format=raw&u=liam&t=58f34e25e9933bf23324f772989630c0&s=q9JCU&v=1.10.2&f=json&c=volusonic to favourites with specific volusonic method Sep 14 22:59:04 volumio-office volumio[994]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 22:59:04 volumio-office volumio[994]: TypeError: Cannot read property 'then' of undefined Sep 14 22:59:04 volumio-office volumio[994]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:679:20) Sep 14 22:59:04 volumio-office volumio[994]: at Socket.emit (events.js:315:20) Sep 14 22:59:04 volumio-office volumio[994]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 14 22:59:04 volumio-office volumio[994]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 14 22:59:04 volumio-office volumio[994]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 22:59:05 volumio-office sudo[1708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 22:58 Sep 14 22:59:05 volumio-office sudo[1708]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"