-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Sun 2025-06-08 11:20:10 +08. -- Jun 08 11:19:04 minidsp-shd volumio[678]: info: MyVolumio login type: Token Jun 08 11:19:22 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 11:19:22 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 11:19:22 minidsp-shd volumio[678]: info: Discovery: Getting this device information Jun 08 11:19:22 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioGetState Jun 08 11:19:22 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 11:19:22 minidsp-shd volumio[678]: Searching plugin music_service/tidal Jun 08 11:19:22 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 11:19:23 minidsp-shd volumio[678]: info: searchTIDALUri took 636 milliseconds Jun 08 11:19:23 minidsp-shd volumio[678]: info: search took 638 milliseconds Jun 08 11:19:23 minidsp-shd volumio[678]: info: All search sources collected, pushing search results Jun 08 11:19:30 minidsp-shd volumio[678]: Searching plugin music_service/tidal Jun 08 11:19:30 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 11:19:31 minidsp-shd volumio[678]: info: searchTIDALUri took 557 milliseconds Jun 08 11:19:31 minidsp-shd volumio[678]: info: search took 559 milliseconds Jun 08 11:19:34 minidsp-shd volumio[678]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 08 11:19:34 minidsp-shd volumio[678]: info: Retrying Login Due to Network Error Jun 08 11:19:34 minidsp-shd volumio[678]: Searching plugin music_service/tidal Jun 08 11:19:34 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 11:19:35 minidsp-shd volumio[678]: info: searchTIDALUri took 552 milliseconds Jun 08 11:19:35 minidsp-shd volumio[678]: info: search took 554 milliseconds Jun 08 11:19:36 minidsp-shd volumio[678]: Searching plugin music_service/tidal Jun 08 11:19:36 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 11:19:36 minidsp-shd volumio[678]: info: searchTIDALUri took 579 milliseconds Jun 08 11:19:36 minidsp-shd volumio[678]: info: search took 581 milliseconds Jun 08 11:19:41 minidsp-shd volumio[678]: Searching plugin music_service/tidal Jun 08 11:19:41 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , search Jun 08 11:19:41 minidsp-shd volumio[678]: info: searchTIDALUri took 572 milliseconds Jun 08 11:19:41 minidsp-shd volumio[678]: info: search took 575 milliseconds Jun 08 11:19:41 minidsp-shd volumio[678]: info: All search sources collected, pushing search results Jun 08 11:19:44 minidsp-shd volumio[678]: info: MyVolumio login type: Token Jun 08 11:19:47 minidsp-shd volumio[678]: info: Preload queue cleared Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::ClearQueue Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::stop Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::stPlaybackTimer Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::updateTrackBlock Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::getTrackBlock Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:47 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:47 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::serviceStop Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreCommandRouter::serviceStop Jun 08 11:19:47 minidsp-shd volumio[678]: info: [1749352787922] ControllerTidal::stop Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 11:19:47 minidsp-shd volumio[678]: info: ControllerMpd::stop Jun 08 11:19:47 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::clearPlayQueue Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::saveQueue Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushQueue Jun 08 11:19:47 minidsp-shd volumio[678]: info: CoreStateMachine::addQueueItems Jun 08 11:19:47 minidsp-shd volumio[678]: info: CorePlayQueue::addQueueItems Jun 08 11:19:47 minidsp-shd volumio[678]: info: Preload queue cleared Jun 08 11:19:47 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/240175991 Jun 08 11:19:47 minidsp-shd volumio[678]: info: Exploding uri tidal://song/240175991 in service tidal Jun 08 11:19:47 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/318124774 Jun 08 11:19:47 minidsp-shd volumio[678]: info: Exploding uri tidal://song/318124774 in service tidal Jun 08 11:19:48 minidsp-shd volumio[678]: info: Jun 08 11:19:48 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand stop took 90 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:48 minidsp-shd volumio[678]: info: Jun 08 11:19:48 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:48 minidsp-shd volumio[678]: info: Jun 08 11:19:48 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand status took 13 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand status took 8 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand status took 5 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:19:48 minidsp-shd volumio[678]: 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":"291444131&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/291444131&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: No code Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:19:48 minidsp-shd volumio[678]: 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":"291444131&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/291444131&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: No code Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:19:48 minidsp-shd volumio[678]: 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":"291444131&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/291444131&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:48 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus stop Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: No code Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:48 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:48 minidsp-shd volumio[678]: info: ------------------------------ 180ms Jun 08 11:19:48 minidsp-shd volumio[678]: info: ------------------------------ 175ms Jun 08 11:19:48 minidsp-shd volumio[678]: info: ------------------------------ 173ms Jun 08 11:19:48 minidsp-shd volumio[678]: info: explodeTIDALUri took 781 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: info: explodeTIDALUri took 1194 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushQueue Jun 08 11:19:49 minidsp-shd volumio[678]: info: CorePlayQueue::saveQueue Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::updateTrackBlock Jun 08 11:19:49 minidsp-shd volumio[678]: info: CorePlayQueue::getTrackBlock Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPlay Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::play index 1 Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::stop Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::play index undefined Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:19:49 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::startPlaybackTimer Jun 08 11:19:49 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 08 11:19:49 minidsp-shd volumio[678]: info: [1749352789154] ControllerTidal::clearAddPlayTrack Jun 08 11:19:49 minidsp-shd volumio[678]: info: Getting stream with soundQuality LOSSLESS Jun 08 11:19:49 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 11:19:49 minidsp-shd volumio[678]: info: sendMpdCommand stop took 7 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: info: sendMpdCommand clear took 10 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS" Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: error: updateQueue error: null Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 16ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS" took 12 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 11ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 9ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 11:19:49 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand play Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: info: Jun 08 11:19:49 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:19:49 minidsp-shd volumio[678]: STREAMING PROXY: Handling url /?data=tidal://song/318124774&soundQuality=LOSSLESS Jun 08 11:19:49 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 24ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: sendMpdCommand play took 18 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 17ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: ------------------------------ 14ms Jun 08 11:19:49 minidsp-shd volumio[678]: info: Executing endpoint getStreamUrltidal Jun 08 11:19:49 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 08 11:19:49 minidsp-shd volumio[678]: info: getStreamUrl took 726 milliseconds Jun 08 11:19:49 minidsp-shd volumio[678]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEicxNjcyMWJjOWQ2ODMxZDU2MmEzZjMwMDYwNzYxNmNiZF82MS5tcDQ/0.flac?token=1749356389~Y2IwODRiZTRmODNiODgzNDIwYzZkM2VkMzMzOTQ0MDdkZDgzYWFkNQ== Jun 08 11:19:50 minidsp-shd volumio[678]: STREAMING PROXY: Response: 200, length: 14000072 Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 22 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 18 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 13 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: Jun 08 11:19:50 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 28 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 16 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand status took 7 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus stop Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:19:50 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:19:50 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 164ms Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 199ms Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 196ms Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 160 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 153 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 154 milliseconds Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:19:50 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:19:50 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"318124774&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/318124774&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:19:50 minidsp-shd volumio[678]: verbose: CURRENT POSITION 1 Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:19:50 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:19:50 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 518ms Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 503ms Jun 08 11:19:50 minidsp-shd volumio[678]: info: ------------------------------ 501ms Jun 08 11:19:51 minidsp-shd volumio[678]: STREAMING PROXY: Client dropped request, destroying Jun 08 11:19:52 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 11:19:52 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 11:19:52 minidsp-shd volumio[678]: info: Discovery: Getting this device information Jun 08 11:19:52 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioGetState Jun 08 11:19:52 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 11:19:53 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 08 11:19:55 minidsp-shd volumio[678]: info: browseTIDALUri took 1279 milliseconds Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preload queue cleared Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317065 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317066 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317067 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317068 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317069 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317070 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317071 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317072 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317073 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317074 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Preloading song: tidal://song/190317075 Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317065 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317066 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317067 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317068 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317069 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Executing endpoint getSimilarAlbums Jun 08 11:19:55 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317070 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317071 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317072 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317073 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317074 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: Exploding uri tidal://song/190317075 in service tidal Jun 08 11:19:55 minidsp-shd volumio[678]: info: explodeTIDALUri took 537 milliseconds Jun 08 11:19:55 minidsp-shd volumio[678]: info: explodeTIDALUri took 561 milliseconds Jun 08 11:19:55 minidsp-shd volumio[678]: info: explodeTIDALUri took 564 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 598 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 580 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 581 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 540 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 549 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 1086 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 1020 milliseconds Jun 08 11:19:56 minidsp-shd volumio[678]: info: explodeTIDALUri took 1015 milliseconds Jun 08 11:19:58 minidsp-shd volumio[678]: info: Executing endpoint metavolumio Jun 08 11:19:58 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 11:19:59 minidsp-shd volumio[678]: info: Executing endpoint metavolumio Jun 08 11:19:59 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 11:20:00 minidsp-shd volumio[678]: info: Preload queue cleared Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::ClearQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::stop Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::stPlaybackTimer Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::updateTrackBlock Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrackBlock Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:00 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::serviceStop Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 1 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::serviceStop Jun 08 11:20:00 minidsp-shd volumio[678]: info: [1749352800831] ControllerTidal::stop Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::stop Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::clearPlayQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::saveQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::addQueueItems Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::addQueueItems Jun 08 11:20:00 minidsp-shd volumio[678]: info: Preload queue cleared Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317065 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317065 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317066 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317066 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317067 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317067 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317068 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317068 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317069 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317069 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317070 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317070 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317071 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317071 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317072 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317072 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317073 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317073 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317074 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317074 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Adding Item to queue: tidal://song/190317075 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Using cached record of: tidal://song/190317075 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::saveQueue Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::updateTrackBlock Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrackBlock Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPlay Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::play index 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::stop Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::play index undefined Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::startPlaybackTimer Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: [1749352800903] ControllerTidal::clearAddPlayTrack Jun 08 11:20:00 minidsp-shd volumio[678]: info: Getting stream with soundQuality LOSSLESS Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 11:20:00 minidsp-shd volumio[678]: info: Jun 08 11:20:00 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand stop took 96 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand stop took 26 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 11:20:00 minidsp-shd volumio[678]: info: Jun 08 11:20:00 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:00 minidsp-shd volumio[678]: info: Jun 08 11:20:00 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:00 minidsp-shd volumio[678]: info: Jun 08 11:20:00 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:00 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand status took 33 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand clear took 31 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand status took 26 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: info: sendMpdCommand status took 23 milliseconds Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS" Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:00 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:00 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 08 11:20:00 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:00 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:00 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:01 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:01 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:01 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:01 minidsp-shd volumio[678]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 73ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 69ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:01 minidsp-shd volumio[678]: error: updateQueue error: null Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 64ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 58 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS" took 57 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 15ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 13ms Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:01 minidsp-shd volumio[678]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand play Jun 08 11:20:01 minidsp-shd volumio[678]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 108ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:01 minidsp-shd volumio[678]: STREAMING PROXY: Handling url /?data=tidal://song/190317065&soundQuality=LOSSLESS Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces system playlist update Jun 08 11:20:01 minidsp-shd volumio[678]: info: Ignoring MPD Status Update Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 57ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand play took 49 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 45ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: ------------------------------ 42ms Jun 08 11:20:01 minidsp-shd volumio[678]: info: Executing endpoint getStreamUrltidal Jun 08 11:20:01 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 08 11:20:01 minidsp-shd volumio[678]: error: Failed request for metavolumio API Jun 08 11:20:01 minidsp-shd volumio[678]: info: Executing endpoint metavolumio Jun 08 11:20:01 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 11:20:01 minidsp-shd volumio[678]: info: getStreamUrl took 573 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEic4ZjVhZGMxNzIwMGQ5Njc4ZTVlOGY1Yjg3NGM0YjRmM182MS5tcDQ/0.flac?token=1749356401~MzRhNjQ3MTU5NDU0NTNhZmY1ZWZiYzZmNmI3YjI4N2FjZDM5ZDc0Yw== Jun 08 11:20:01 minidsp-shd volumio[678]: STREAMING PROXY: Response: 200, length: 27520564 Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:01 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:01 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:01 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:01 minidsp-shd volumio[678]: info: Jun 08 11:20:01 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand status took 13 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand status took 11 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: info: sendMpdCommand status took 7 milliseconds Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:01 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: Jun 08 11:20:02 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:02 minidsp-shd volumio[678]: info: Jun 08 11:20:02 minidsp-shd volumio[678]: ---------------------------- MPD announces state update: player Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::getState Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand status Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand status took 28 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand status took 13 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus stop Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:20:02 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:20:02 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 142ms Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 190ms Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 188ms Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand status took 169 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 161 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 158 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseState Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:20:02 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:20:02 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 423ms Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 411ms Jun 08 11:20:02 minidsp-shd volumio[678]: info: sendMpdCommand playlistinfo took 362 milliseconds Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: ControllerMpd::parseTrackInfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: ControllerMpd::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::servicePushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CorePlayQueue::getTrack 0 Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":316,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"190317065&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/190317065&soundQuality=LOSSLESS","trackType":"tidal"} Jun 08 11:20:02 minidsp-shd volumio[678]: verbose: CURRENT POSITION 0 Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState stateService play Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::syncState currentStatus play Jun 08 11:20:02 minidsp-shd volumio[678]: info: Received an update from plugin. extracting info from payload Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreStateMachine::pushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: CoreCommandRouter::volumioPushState Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output update for this device Jun 08 11:20:02 minidsp-shd volumio[678]: info: MRS: Pushing multiroomSync output Jun 08 11:20:02 minidsp-shd volumio[678]: info: ------------------------------ 619ms Jun 08 11:20:03 minidsp-shd volumio[678]: STREAMING PROXY: Client dropped request, destroying Jun 08 11:20:03 minidsp-shd volumio[678]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/Time/6353794e-b196-4a54-be6b-3fd29f179443.jpg' Jun 08 11:20:04 minidsp-shd volumio[678]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/Interstellar%3A%20Original%20Motion%20Picture%20Soundtrack/d1834fc3-939c-47f1-93bc-0929b14165b3.png' Jun 08 11:20:04 minidsp-shd volumio[678]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/Inception%3A%20Music%20From%20The%20Motion%20Picture/d419f474-96e9-43eb-a689-0cbd66335f5f.jpg' Jun 08 11:20:04 minidsp-shd volumio[678]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/The%20Dark%20Knight%3A%20Original%20Motion%20Picture%20Soundtrack/78b9359b-601f-4e4c-8aa9-19a2494385e1.jpg' Jun 08 11:20:04 minidsp-shd volumio[678]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Hans%20Zimmer/Dunkirk%3A%20Original%20Motion%20Picture%20Soundtrack/e45d810a-0ec7-4d03-bba8-2bf3a1672a8b.jpg' Jun 08 11:20:06 minidsp-shd volumio[678]: info: Adding tidal://album/190317064 to favourites with specific tidal method Jun 08 11:20:09 minidsp-shd volumio[678]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 11:20:09 minidsp-shd volumio[678]: Error: connect ETIMEDOUT 31.13.76.65:80 Jun 08 11:20:09 minidsp-shd volumio[678]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 08 11:20:09 minidsp-shd volumio[678]: errno: -110, Jun 08 11:20:09 minidsp-shd volumio[678]: code: 'ETIMEDOUT', Jun 08 11:20:09 minidsp-shd volumio[678]: syscall: 'connect', Jun 08 11:20:09 minidsp-shd volumio[678]: address: '31.13.76.65', Jun 08 11:20:09 minidsp-shd volumio[678]: port: 80 Jun 08 11:20:09 minidsp-shd volumio[678]: } Jun 08 11:20:09 minidsp-shd volumio[678]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 11:20:10 minidsp-shd sudo[3854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 11:19 Jun 08 11:20:10 minidsp-shd sudo[3854]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"