-- Logs begin at Fri 2024-08-23 16:14:16 +07, end at Fri 2024-08-23 17:51:54 +07. -- Aug 23 17:50:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:22 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:22 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:30 chalusxno02 sudo[10863]: volumio : unable to resolve host chalusxno02 Aug 23 17:50:30 chalusxno02 sudo[10863]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:50:30 chalusxno02 sudo[10863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:50:30 chalusxno02 sudo[10863]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:50:30 chalusxno02 sudo[10863]: pam_unix(sudo:session): session closed for user root Aug 23 17:50:30 chalusxno02 sudo[10865]: volumio : unable to resolve host chalusxno02 Aug 23 17:50:30 chalusxno02 sudo[10865]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:50:30 chalusxno02 sudo[10865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:50:30 chalusxno02 sudo[10865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:50:30 chalusxno02 sudo[10865]: pam_unix(sudo:session): session closed for user root Aug 23 17:50:30 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 23 17:50:31 chalusxno02 sudo[10869]: volumio : unable to resolve host chalusxno02 Aug 23 17:50:31 chalusxno02 sudo[10869]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:50:31 chalusxno02 sudo[10869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:50:31 chalusxno02 sudo[10869]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:50:31 chalusxno02 sudo[10869]: pam_unix(sudo:session): session closed for user root Aug 23 17:50:31 chalusxno02 sudo[10871]: volumio : unable to resolve host chalusxno02 Aug 23 17:50:31 chalusxno02 sudo[10871]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:50:31 chalusxno02 sudo[10871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:50:31 chalusxno02 sudo[10871]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:50:31 chalusxno02 sudo[10871]: pam_unix(sudo:session): session closed for user root Aug 23 17:50:31 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 17:50:31 chalusxno02 volumio[1158]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 23 17:50:31 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:31 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:31 chalusxno02 volumio[1158]: info: Listing playlists Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 17:50:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:50:32 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:50:32 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:50:32 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:50:33 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:33 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:50:34 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:34 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:41 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 23 17:50:45 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:50:45 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 23 17:50:50 chalusxno02 volumio[1158]: info: Received Get System Version Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 17:50:50 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:50 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:50:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:50:52 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:50:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:50:52 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:50:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:50:53 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:50:53 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:50:54 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:00 chalusxno02 nmbd[863]: [2024/08/23 17:51:00.847723, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 23 17:51:00 chalusxno02 nmbd[863]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.59 for name WORKGROUP<1d>. Aug 23 17:51:00 chalusxno02 nmbd[863]: This response was from IP 192.168.1.222, reporting an IP address of 192.168.1.222. Aug 23 17:51:01 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:51:01 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:10 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:51:10 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:10 chalusxno02 volumio[1158]: info: Preloading song: volusonic/track/track-2844 Aug 23 17:51:10 chalusxno02 volumio[1158]: info: Preloading song: volusonic/track/track-2842 Aug 23 17:51:10 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:10 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:11 chalusxno02 volumio[1158]: info: Executing endpoint getSimilarAlbums Aug 23 17:51:11 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 23 17:51:11 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:11 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:11 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:11 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::serviceStop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 5 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::serviceStop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273032] ControllerVolusonic::stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::stop Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273037] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273039] ControllerVolusonic: MPD player state update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Aug 23 17:51:13 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 7 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Aug 23 17:51:13 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:13 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ------------------------------ 3ms Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ------------------------------ 4ms Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Aug 23 17:51:13 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273713] ControllerVolusonic::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273905] ControllerVolusonic: MPD player state update Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Aug 23 17:51:13 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Aug 23 17:51:13 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 24 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 24 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: [1724410273930] ControllerVolusonic::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"16 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:13 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ------------------------------ 28ms Aug 23 17:51:13 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 17:51:13 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:13 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:13 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:13 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274063] ControllerVolusonic: MPD player state update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274065] ControllerVolusonic::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 18ms Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274470] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:14 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274540] ControllerVolusonic::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274901] ControllerVolusonic: MPD player state update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274903] ControllerVolusonic::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274908] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 10 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 5 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:14 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 14ms Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 3 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:14 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 2ms Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Aug 23 17:51:14 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand play took 1 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:14 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:14 chalusxno02 volumio[1158]: info: [1724410274985] ControllerVolusonic::pushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:14 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:14 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::serviceStop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::serviceStop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275076] ControllerVolusonic::stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::stop Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275078] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275080] ControllerVolusonic: MPD player state update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 4 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 3 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 2ms Aug 23 17:51:15 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275178] ControllerVolusonic::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275425] ControllerVolusonic: MPD player state update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275427] ControllerVolusonic::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 5ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275496] ControllerVolusonic: MPD player state update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275497] ControllerVolusonic::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275504] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 9ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 3 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 4 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:15 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand play took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275581] ControllerVolusonic::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275749] ControllerVolusonic: MPD player state update Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Aug 23 17:51:15 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: [1724410275751] ControllerVolusonic::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:15 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 17:51:15 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:15 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:15 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:15 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Aug 23 17:51:16 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:16 chalusxno02 volumio[1158]: info: [1724410276325] ControllerVolusonic: MPD player state update Aug 23 17:51:16 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Aug 23 17:51:16 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:16 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:16 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:16 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:16 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:16 chalusxno02 volumio[1158]: info: [1724410276325] ControllerVolusonic::pushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:16 chalusxno02 volumio[1158]: info: sendMpdCommand status took 4 milliseconds Aug 23 17:51:16 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:16 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:16 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:16 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:16 chalusxno02 volumio[1158]: info: ------------------------------ 7ms Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 1 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:18 chalusxno02 volumio[1158]: info: [1724410278770] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 0 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Aug 23 17:51:18 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 0 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:18 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:18 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Aug 23 17:51:18 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:18 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:18 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand play took 1 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:18 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:18 chalusxno02 volumio[1158]: info: [1724410278839] ControllerVolusonic::pushState Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:18 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:18 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:19 chalusxno02 volumio[1158]: info: [1724410279032] ControllerVolusonic: MPD player state update Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Aug 23 17:51:19 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Aug 23 17:51:19 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: [1724410279034] ControllerVolusonic::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"8 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Aug 23 17:51:19 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:19 chalusxno02 volumio[1158]: info: [1724410279186] ControllerVolusonic: MPD player state update Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Aug 23 17:51:19 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:19 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:19 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: [1724410279187] ControllerVolusonic::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:19 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:19 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:19 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:19 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:19 chalusxno02 volumio[1158]: info: ------------------------------ 5ms Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 1 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:20 chalusxno02 volumio[1158]: info: [1724410280898] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 0 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Aug 23 17:51:20 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 1 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:20 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:20 chalusxno02 volumio[1158]: info: ------------------------------ 73ms Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Aug 23 17:51:20 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:20 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:20 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:20 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:20 chalusxno02 volumio[1158]: info: [1724410280973] ControllerVolusonic::pushState Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:20 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:20 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281163] ControllerVolusonic: MPD player state update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281165] ControllerVolusonic::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"16 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 5ms Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281312] ControllerVolusonic: MPD player state update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281313] ControllerVolusonic::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2844 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2844 in service volusonic Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2842 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2842 in service volusonic Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281504] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:21 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand play took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281572] ControllerVolusonic::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281915] ControllerVolusonic: MPD player state update Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Aug 23 17:51:21 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: [1724410281918] ControllerVolusonic::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:21 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:21 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:21 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:21 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:21 chalusxno02 volumio[1158]: info: ------------------------------ 7ms Aug 23 17:51:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:22 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:22 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:22 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:23 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:24 chalusxno02 volumio[1158]: error: Failed request for metavolumio API Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288350] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Aug 23 17:51:28 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:28 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Aug 23 17:51:28 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ------------------------------ 0ms Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288451] ControllerVolusonic::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288777] ControllerVolusonic: MPD player state update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Aug 23 17:51:28 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288779] ControllerVolusonic::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2844&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288783] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand clearerror took 9 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 5 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:28 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ------------------------------ 13ms Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Aug 23 17:51:28 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 2 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:28 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ------------------------------ 68ms Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Aug 23 17:51:28 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand play took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:28 chalusxno02 volumio[1158]: info: [1724410288860] ControllerVolusonic::pushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:28 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:28 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:29 chalusxno02 volumio[1158]: info: [1724410289141] ControllerVolusonic: MPD player state update Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Aug 23 17:51:29 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Aug 23 17:51:29 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand status took 2 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: [1724410289143] ControllerVolusonic::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2842&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand status took 5 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ------------------------------ 10ms Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Aug 23 17:51:29 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:29 chalusxno02 volumio[1158]: info: [1724410289330] ControllerVolusonic: MPD player state update Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Aug 23 17:51:29 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:29 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:29 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: [1724410289331] ControllerVolusonic::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 23 17:51:29 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 1 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService stop Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::stPlaybackTimer Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:29 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:29 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:29 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:29 chalusxno02 volumio[1158]: info: ------------------------------ 9ms Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:31 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:33 chalusxno02 sudo[10944]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:33 chalusxno02 sudo[10944]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:33 chalusxno02 sudo[10944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:51:33 chalusxno02 sudo[10944]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:33 chalusxno02 sudo[10944]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:33 chalusxno02 sudo[10946]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:33 chalusxno02 sudo[10946]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:33 chalusxno02 sudo[10946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:51:33 chalusxno02 sudo[10946]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:33 chalusxno02 sudo[10946]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:33 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 23 17:51:33 chalusxno02 sudo[10950]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:33 chalusxno02 sudo[10950]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:33 chalusxno02 sudo[10950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:51:33 chalusxno02 sudo[10950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:33 chalusxno02 sudo[10950]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:33 chalusxno02 sudo[10952]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:33 chalusxno02 sudo[10952]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:33 chalusxno02 sudo[10952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:51:33 chalusxno02 sudo[10952]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:33 chalusxno02 sudo[10952]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:33 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 17:51:33 chalusxno02 volumio[1158]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 23 17:51:33 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:33 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:33 chalusxno02 volumio[1158]: info: Listing playlists Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 17:51:33 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:34 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:34 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:34 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:51:35 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:35 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:35 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:51:36 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:36 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:36 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:39 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volusonic , handleBrowseUri Aug 23 17:51:39 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:39 chalusxno02 volumio[1158]: info: Executing endpoint getSimilarAlbums Aug 23 17:51:39 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Aug 23 17:51:39 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:39 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:39 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:39 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::ClearQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::clearPlayQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::addQueueItems Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::addQueueItems Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Preload queue cleared Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2830 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2830 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2829 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2829 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2831 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2831 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2833 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2833 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2834 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2834 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2832 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2832 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2835 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2835 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2837 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2837 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2836 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2836 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2841 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2841 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Adding Item to queue: volusonic/track/track-2840 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Exploding uri volusonic/track/track-2840 in service volusonic Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::saveQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::updateTrackBlock Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrackBlock Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPlay Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::play index 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::stop Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::play index undefined Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::startPlaybackTimer Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302011] ControllerVolusonic::clearAddPlayTrack Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand stop Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand stop took 2 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand clear Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand clear took 0 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand load "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:42 chalusxno02 volumio[1158]: error: updateQueue error: null Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand add "http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic" took 0 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand play took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 0 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302114] ControllerVolusonic::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus stop Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302204] ControllerVolusonic: MPD player state update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 25 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 25 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302230] ControllerVolusonic::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":349,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","artist":null,"album":null,"uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 31ms Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces system playlist update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Ignoring MPD Status Update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302282] ControllerVolusonic: MPD player state update Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Aug 23 17:51:42 chalusxno02 volumio[1158]: ---------------------------- MPD announces state update: player Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::getState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand status Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 1ms Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand status took 1 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseState Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: [1724410302283] ControllerVolusonic::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: STATE SERVICE {"status":"play","position":0,"seek":541,"duration":349,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Lights of Barcelona","artist":"Marc Vallee Trio","album":"Audiophile Analog Collection Vol. 2","uri":"http://mdeblynk.thddns.net:2627/nextcloud/apps/music/subsonic/rest/stream.view?id=track-2830&format=raw&u=chalus&p=enc:6861724b364b337659755965&v=1.10.2&f=json&c=volusonic","trackType":""} Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: CURRENT POSITION 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState stateService play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::syncState currentStatus play Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Received an update from plugin. extracting info from payload Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreStateMachine::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioPushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output update for this device Aug 23 17:51:42 chalusxno02 volumio[1158]: info: MRS: Pushing multiroomSync output Aug 23 17:51:42 chalusxno02 volumio[1158]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 23 17:51:42 chalusxno02 volumio[1158]: verbose: ControllerMpd::parseTrackInfo Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ControllerMpd::pushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CoreCommandRouter::servicePushState Aug 23 17:51:42 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:42 chalusxno02 volumio[1158]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current volusonic Received mpd Aug 23 17:51:42 chalusxno02 volumio[1158]: info: ------------------------------ 6ms Aug 23 17:51:43 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:44 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:48 chalusxno02 sudo[10957]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:48 chalusxno02 sudo[10957]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:48 chalusxno02 sudo[10957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:51:48 chalusxno02 sudo[10957]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:48 chalusxno02 sudo[10957]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:48 chalusxno02 sudo[10959]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:48 chalusxno02 sudo[10959]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:48 chalusxno02 sudo[10959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:51:48 chalusxno02 sudo[10959]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:48 chalusxno02 sudo[10959]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:48 chalusxno02 volumio[1158]: error: Failed request for metavolumio API Aug 23 17:51:48 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 23 17:51:48 chalusxno02 sudo[10963]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:48 chalusxno02 sudo[10963]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:48 chalusxno02 sudo[10963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 23 17:51:48 chalusxno02 sudo[10963]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:48 chalusxno02 sudo[10963]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:48 chalusxno02 sudo[10965]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:48 chalusxno02 sudo[10965]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:48 chalusxno02 sudo[10965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 23 17:51:48 chalusxno02 sudo[10965]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 23 17:51:48 chalusxno02 sudo[10965]: pam_unix(sudo:session): session closed for user root Aug 23 17:51:48 chalusxno02 volumio[1158]: verbose: New Socket.io Connection to 192.168.1.59 from 192.168.1.53 UA: Mozilla/5.0 (Linux; Android 14; ASUS_AI2201_C Build/UKQ1.230924.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/127.0.6533.103 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetVisibleSources Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 23 17:51:48 chalusxno02 volumio[1158]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 23 17:51:48 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:48 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:48 chalusxno02 volumio[1158]: info: Listing playlists Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 23 17:51:48 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 17:51:49 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:51:50 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:50 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetQueue Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreStateMachine::getQueue Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CorePlayQueue::getQueue Aug 23 17:51:50 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:50 chalusxno02 volumio[1158]: info: Executing endpoint metavolumio Aug 23 17:51:50 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 23 17:51:51 chalusxno02 volumio[1158]: info: Received Get System Version Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 23 17:51:51 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:51 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 23 17:51:51 chalusxno02 volumio[1158]: info: Received Get System Info Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:51 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:51 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 23 17:51:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 23 17:51:52 chalusxno02 volumio[1158]: info: Discovery: Getting this device information Aug 23 17:51:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::volumioGetState Aug 23 17:51:52 chalusxno02 volumio[1158]: info: CorePlayQueue::getTrack 0 Aug 23 17:51:52 chalusxno02 volumio[1158]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 23 17:51:54 chalusxno02 volumio[1158]: info: Adding volusonic/track/track-2833 to favourites with specific volusonic method Aug 23 17:51:54 chalusxno02 volumio[1158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 17:51:54 chalusxno02 volumio[1158]: TypeError: Cannot read property 'then' of undefined Aug 23 17:51:54 chalusxno02 volumio[1158]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:679:20) Aug 23 17:51:54 chalusxno02 volumio[1158]: at Socket.emit (events.js:400:28) Aug 23 17:51:54 chalusxno02 volumio[1158]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 23 17:51:54 chalusxno02 volumio[1158]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Aug 23 17:51:54 chalusxno02 volumio[1158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 23 17:51:54 chalusxno02 sudo[10977]: volumio : unable to resolve host chalusxno02 Aug 23 17:51:54 chalusxno02 sudo[10977]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Aug 23 17:51:54 chalusxno02 sudo[10977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-23 17:50 Aug 23 17:51:54 chalusxno02 sudo[10977]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"