-- Logs begin at Sat 2025-06-14 15:07:24 +08, end at Sun 2025-06-15 21:42:47 +08. -- Jun 15 21:41:05 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 21:41:05 minidsp-shd volumio[2301]: info: browseTIDALUri took 270 milliseconds Jun 15 21:41:05 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:05 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:06 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 21:41:06 minidsp-shd volumio[2301]: info: browseTIDALUri took 703 milliseconds Jun 15 21:41:07 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:07 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 21:41:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 21:41:07 minidsp-shd volumio[2301]: info: Discovery: Getting this device information Jun 15 21:41:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioGetState Jun 15 21:41:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 21:41:11 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 15 21:41:12 minidsp-shd volumio[2301]: info: browseTIDALUri took 1243 milliseconds Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593956 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593957 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593958 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593959 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593960 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593961 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593962 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593963 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593964 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593965 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593966 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Preloading song: tidal://song/593967 Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593956 in service tidal Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593957 in service tidal Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Executing endpoint getSimilarAlbums Jun 15 21:41:12 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593958 in service tidal Jun 15 21:41:12 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593959 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:41:13 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:41:13 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593960 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593961 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593962 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593963 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593964 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593965 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593966 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: Exploding uri tidal://song/593967 in service tidal Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 596 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 582 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 654 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 627 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 735 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 551 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 661 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 623 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 540 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 968 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 538 milliseconds Jun 15 21:41:13 minidsp-shd volumio[2301]: info: explodeTIDALUri took 632 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::ClearQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::updateTrackBlock Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrackBlock Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::stPlaybackTimer Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 6 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:16 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:16 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::serviceStop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 6 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::serviceStop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: [1749994876165] ControllerTidal::stop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 21:41:16 minidsp-shd volumio[2301]: info: ControllerMpd::stop Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::clearPlayQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::addQueueItems Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::addQueueItems Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593956 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593956 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593957 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593957 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593958 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593958 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593959 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593959 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593960 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593960 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593961 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593961 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593962 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593962 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593963 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593963 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593964 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593964 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593965 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593965 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593966 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593966 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Adding Item to queue: tidal://song/593967 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Using cached record of: tidal://song/593967 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::updateTrackBlock Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrackBlock Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPlay Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::play index 1 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::play index undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::startPlaybackTimer Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:16 minidsp-shd volumio[2301]: info: [1749994876221] ControllerTidal::clearAddPlayTrack Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Getting stream with soundQuality LOSSLESS Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:41:16 minidsp-shd volumio[2301]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Retrying Login Due to Network Error Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Jun 15 21:41:16 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 81 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 28 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Jun 15 21:41:16 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand status took 31 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand clear took 28 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/593957&soundQuality=LOSSLESS" Jun 15 21:41:16 minidsp-shd volumio[2301]: error: updateQueue error: null Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Jun 15 21:41:16 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:41:16 minidsp-shd volumio[2301]: info: ------------------------------ 14ms Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/593957&soundQuality=LOSSLESS" took 10 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 21:41:16 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand play Jun 15 21:41:16 minidsp-shd volumio[2301]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 15 21:41:16 minidsp-shd volumio[2301]: info: ------------------------------ 55ms Jun 15 21:41:16 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593957&soundQuality=LOSSLESS Jun 15 21:41:16 minidsp-shd volumio[2301]: info: ------------------------------ 27ms Jun 15 21:41:16 minidsp-shd volumio[2301]: info: sendMpdCommand play took 19 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:16 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:16 minidsp-shd volumio[2301]: info: getStreamUrl took 611 milliseconds Jun 15 21:41:16 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWQ4Zjg5OGQwOTc1YzgyN2EyNWM3MmRkNGU5NjMzZV82MS5tcDQ/0.flac?token=1749998476~ZTA1OGU3ODk3MzY2N2Y4YzU2ODViNWRmM2EyZDRiOTA1NDAzY2M5MA== Jun 15 21:41:17 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 200, length: 33292200 Jun 15 21:41:17 minidsp-shd volumio[2301]: info: Jun 15 21:41:17 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:17 minidsp-shd volumio[2301]: info: sendMpdCommand status took 4 milliseconds Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:17 minidsp-shd volumio[2301]: info: Jun 15 21:41:17 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:17 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"593957&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593957&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 1 Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus stop Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ------------------------------ 23ms Jun 15 21:41:17 minidsp-shd volumio[2301]: info: sendMpdCommand status took 10 milliseconds Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:17 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"593957&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593957&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:41:17 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 1 Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus play Jun 15 21:41:17 minidsp-shd volumio[2301]: info: Received an update from plugin. extracting info from payload Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:17 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:17 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:17 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:17 minidsp-shd volumio[2301]: info: ------------------------------ 63ms Jun 15 21:41:26 minidsp-shd volumio[2301]: info: MyVolumio login type: Token Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioSeek Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreStateMachine::seek Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:26 minidsp-shd volumio[2301]: info: TRACKBLOCK {"album":"真情流露-張學友","albumUri":"tidal://album/593955","albumart":"https://resources.tidal.com/images/6b2fd946/14f3/4056/8450/a1731c523e15/640x640.jpg","artist":"張學友","artistUri":"tidal://artist/9108786","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":290,"explicit":false,"name":"愛得比你深","samplerate":"44.1 KHz","service":"tidal","title":"愛得比你深","trackType":"tidal","tracknumber":2,"type":"track","uri":"tidal://song/593957","volumeNumber":1} Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreStateMachine::startPlaybackTimer Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:26 minidsp-shd volumio[2301]: info: [1749994886809] ControllerTidal::seek Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 21:41:26 minidsp-shd volumio[2301]: info: ControllerMpd::seek Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:26 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:26 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:26 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:41:26 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593957&soundQuality=LOSSLESS Jun 15 21:41:26 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:26 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:27 minidsp-shd volumio[2301]: info: getStreamUrl took 488 milliseconds Jun 15 21:41:27 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWQ4Zjg5OGQwOTc1YzgyN2EyNWM3MmRkNGU5NjMzZV82MS5tcDQ/0.flac?token=1749998476~ZTA1OGU3ODk3MzY2N2Y4YzU2ODViNWRmM2EyZDRiOTA1NDAzY2M5MA== Jun 15 21:41:27 minidsp-shd volumio[2301]: STREAMING PROXY: Got range request: bytes=5996334- Jun 15 21:41:27 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 206, length: 27295866 Jun 15 21:41:28 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:41:28 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593957&soundQuality=LOSSLESS Jun 15 21:41:28 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:28 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:28 minidsp-shd volumio[2301]: info: getStreamUrl took 486 milliseconds Jun 15 21:41:28 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWQ4Zjg5OGQwOTc1YzgyN2EyNWM3MmRkNGU5NjMzZV82MS5tcDQ/0.flac?token=1749998476~ZTA1OGU3ODk3MzY2N2Y4YzU2ODViNWRmM2EyZDRiOTA1NDAzY2M5MA== Jun 15 21:41:28 minidsp-shd volumio[2301]: STREAMING PROXY: Got range request: bytes=5985603- Jun 15 21:41:29 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 206, length: 27306597 Jun 15 21:41:29 minidsp-shd volumio[2301]: error: null Jun 15 21:41:29 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:41:29 minidsp-shd volumio[2301]: info: Jun 15 21:41:29 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:29 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:29 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593957&soundQuality=LOSSLESS Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:29 minidsp-shd volumio[2301]: info: sendMpdCommand status took 3 milliseconds Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:29 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:29 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":58000,"duration":290,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"683 Kbps","isStreaming":false,"title":"593957&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593957&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:41:29 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 1 Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus play Jun 15 21:41:29 minidsp-shd volumio[2301]: info: Received an update from plugin. extracting info from payload Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:29 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:29 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:29 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:29 minidsp-shd volumio[2301]: info: ------------------------------ 65ms Jun 15 21:41:29 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:29 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:29 minidsp-shd volumio[2301]: info: getStreamUrl took 538 milliseconds Jun 15 21:41:29 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWQ4Zjg5OGQwOTc1YzgyN2EyNWM3MmRkNGU5NjMzZV82MS5tcDQ/0.flac?token=1749998476~ZTA1OGU3ODk3MzY2N2Y4YzU2ODViNWRmM2EyZDRiOTA1NDAzY2M5MA== Jun 15 21:41:29 minidsp-shd volumio[2301]: STREAMING PROXY: Got range request: bytes=1120000- Jun 15 21:41:30 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 206, length: 32172200 Jun 15 21:41:30 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:41:30 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593957&soundQuality=LOSSLESS Jun 15 21:41:30 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:30 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:30 minidsp-shd volumio[2301]: info: getStreamUrl took 487 milliseconds Jun 15 21:41:30 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkOWQ4Zjg5OGQwOTc1YzgyN2EyNWM3MmRkNGU5NjMzZV82MS5tcDQ/0.flac?token=1749998476~ZTA1OGU3ODk3MzY2N2Y4YzU2ODViNWRmM2EyZDRiOTA1NDAzY2M5MA== Jun 15 21:41:30 minidsp-shd volumio[2301]: STREAMING PROXY: Got range request: bytes=6074734- Jun 15 21:41:31 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 206, length: 27217466 Jun 15 21:41:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 21:41:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 21:41:37 minidsp-shd volumio[2301]: info: Discovery: Getting this device information Jun 15 21:41:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioGetState Jun 15 21:41:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 21:41:42 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:41:42 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:41:42 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:41:42 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:41:42 minidsp-shd volumio[2301]: STREAMING PROXY: Error proxying request: Error: read ECONNRESET Jun 15 21:41:42 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Jun 15 21:41:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Jun 15 21:41:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:43 minidsp-shd volumio[2301]: error: updateQueue error: null Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ------------------------------ 8ms Jun 15 21:41:43 minidsp-shd volumio[2301]: info: sendMpdCommand status took 4 milliseconds Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 1 Jun 15 21:41:43 minidsp-shd volumio[2301]: 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} Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 1 Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService stop Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus play Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::play index undefined Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::startPlaybackTimer Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:43 minidsp-shd volumio[2301]: info: [1749994903307] ControllerTidal::clearAddPlayTrack Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Getting stream with soundQuality LOSSLESS Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ------------------------------ 66ms Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:43 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 17 milliseconds Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Jun 15 21:41:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: sendMpdCommand clear took 14 milliseconds Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/593958&soundQuality=LOSSLESS" Jun 15 21:41:43 minidsp-shd volumio[2301]: error: updateQueue error: null Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Jun 15 21:41:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ------------------------------ 33ms Jun 15 21:41:43 minidsp-shd volumio[2301]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/593958&soundQuality=LOSSLESS" took 30 milliseconds Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 21:41:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand play Jun 15 21:41:43 minidsp-shd volumio[2301]: STREAMING PROXY: Handling url /?data=tidal://song/593958&soundQuality=LOSSLESS Jun 15 21:41:43 minidsp-shd volumio[2301]: info: ------------------------------ 19ms Jun 15 21:41:43 minidsp-shd volumio[2301]: info: sendMpdCommand play took 12 milliseconds Jun 15 21:41:43 minidsp-shd volumio[2301]: info: Executing endpoint getStreamUrltidal Jun 15 21:41:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Jun 15 21:41:44 minidsp-shd volumio[2301]: info: getStreamUrl took 556 milliseconds Jun 15 21:41:44 minidsp-shd volumio[2301]: STREAMING PROXY: Got real url: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKwgDEidkYWVkZjZhNDBmMzJkMzNkNDIyZjFjZmM5NjdkYjViY182MS5tcDQ/0.flac?token=1749998503~Y2QzMjJmZjdmMWE1ZDFmNzEzMjEyYzM1YTJhNGZiNWMxMjZjODBmNg== Jun 15 21:41:44 minidsp-shd volumio[2301]: STREAMING PROXY: Response: 200, length: 27532691 Jun 15 21:41:44 minidsp-shd volumio[2301]: info: Jun 15 21:41:44 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:44 minidsp-shd volumio[2301]: info: Jun 15 21:41:44 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:41:44 minidsp-shd volumio[2301]: info: sendMpdCommand status took 5 milliseconds Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:44 minidsp-shd volumio[2301]: info: sendMpdCommand status took 4 milliseconds Jun 15 21:41:44 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"593958&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593958&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 2 Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus stop Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ------------------------------ 23ms Jun 15 21:41:44 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"593958&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593958&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:41:44 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 2 Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus play Jun 15 21:41:44 minidsp-shd volumio[2301]: info: Received an update from plugin. extracting info from payload Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:41:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:41:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:41:44 minidsp-shd volumio[2301]: info: ------------------------------ 62ms Jun 15 21:41:56 minidsp-shd volumio[2301]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 15 21:41:56 minidsp-shd volumio[2301]: info: Retrying Login Due to Network Error Jun 15 21:42:05 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:42:05 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:42:05 minidsp-shd volumio[2301]: info: Executing endpoint metavolumio Jun 15 21:42:05 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 15 21:42:06 minidsp-shd volumio[2301]: info: MyVolumio login type: Token Jun 15 21:42:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 21:42:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 21:42:07 minidsp-shd volumio[2301]: info: Discovery: Getting this device information Jun 15 21:42:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioGetState Jun 15 21:42:07 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 21:42:19 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:19 minidsp-shd volumio[2301]: info: CURURI: music-library Jun 15 21:42:19 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:20 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:20 minidsp-shd volumio[2301]: info: CURURI: music-library/USB Jun 15 21:42:20 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:21 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:21 minidsp-shd volumio[2301]: info: CURURI: music-library/USB/移动硬盘 Jun 15 21:42:21 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:23 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:23 minidsp-shd volumio[2301]: info: CURURI: music-library/USB/移动硬盘/SACD（共1551G） Jun 15 21:42:23 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:36 minidsp-shd volumio[2301]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 15 21:42:36 minidsp-shd volumio[2301]: info: Retrying Login Due to Network Error Jun 15 21:42:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 21:42:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 21:42:37 minidsp-shd volumio[2301]: info: Discovery: Getting this device information Jun 15 21:42:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioGetState Jun 15 21:42:37 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 21:42:38 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::ClearQueue Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::stPlaybackTimer Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::updateTrackBlock Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrackBlock Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:38 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:38 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::serviceStop Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 2 Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreCommandRouter::serviceStop Jun 15 21:42:38 minidsp-shd volumio[2301]: info: [1749994958952] ControllerTidal::stop Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 15 21:42:38 minidsp-shd volumio[2301]: info: ControllerMpd::stop Jun 15 21:42:38 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::clearPlayQueue Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CoreStateMachine::addQueueItems Jun 15 21:42:38 minidsp-shd volumio[2301]: info: CorePlayQueue::addQueueItems Jun 15 21:42:38 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:38 minidsp-shd volumio[2301]: info: Adding Item to queue: music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD Jun 15 21:42:38 minidsp-shd volumio[2301]: info: Exploding uri music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD in service mpd Jun 15 21:42:43 minidsp-shd volumio[2301]: STREAMING PROXY: Client dropped request, destroying Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 4242 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F01.TO%20ME.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F02.WINE%20RED%20NO%20KOKORO.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/02.WINE RED NO KOKORO.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F03.FRIEND.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/03.FRIEND.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F04.NATSU%20NO%20OWARI%20NO%20HARMONY.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/04.NATSU NO OWARI NO HARMONY.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F05.YUME%20NO%20TSUZUKI.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/05.YUME NO TSUZUKI.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F06.HITOMI%20WO%20TOJITE.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/06.HITOMI WO TOJITE.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F07.KOI%20NO%20YOKAN.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/07.KOI NO YOKAN.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F08.ANATA%20NI.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/08.ANATA NI.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F09.KANASHIMI%20NI%20SAYONARA.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/09.KANASHIMI NI SAYONARA.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F10.AOI%20HITOMI%20NO%20ELIS.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/10.AOI HITOMI NO ELIS.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F11.ASA%20NO%20HIZASHI%20NI%20KIMI%20GA%20ITE.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/11.ASA NO HIZASHI NI KIMI GA ITE.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F12.YUBIKIRI.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/12.YUBIKIRI.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ALBUMART /albumart?cacheid=702&web=Koji%20Tamaki%20-%20%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C/%E3%83%AF%E3%82%A4%E3%83%B3%E3%83%AC%E3%83%83%E3%83%89%E3%81%AE%E5%BF%83%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83/extralarge&path=%2Fmnt%2FUSB%2F%E7%A7%BB%E5%8A%A8%E7%A1%AC%E7%9B%98%2FSACD%EF%BC%88%E5%85%B11551G%EF%BC%89%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8CSACD%2F%E7%8E%89%E7%BD%AE%E6%B5%A9%E4%BA%8C%20-%20%E9%85%92%E7%BA%A2%E8%89%B2%E7%9A%84%E5%BF%83DSF%2F13.ANO%20KORO%20HE.dsf&metadata=false Jun 15 21:42:43 minidsp-shd volumio[2301]: info: URI /mnt/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/13.ANO KORO HE.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::updateTrackBlock Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrackBlock Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPlay Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::play index 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::play index undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::startPlaybackTimer Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::clearAddPlayTracks USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::ClearQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::clearPlayQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::addQueueItems Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::addQueueItems Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Adding Item to queue: music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Using cached record of: music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::saveQueue Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::updateTrackBlock Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrackBlock Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPlay Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::play index 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::play index undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::startPlaybackTimer Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::clearAddPlayTracks USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand status took 73 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 39 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand stop took 11 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand clear Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 28 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand clear took 27 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand clear took 27 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand add "USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf" Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand add "USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf" Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: 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":"593958&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/593958&soundQuality=LOSSLESS","trackType":"tidal"} Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:43 minidsp-shd volumio[2301]: info: No code Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:43 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 197ms Jun 15 21:42:43 minidsp-shd volumio[2301]: error: updateQueue error: null Jun 15 21:42:43 minidsp-shd volumio[2301]: error: updateQueue error: null Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces system playlist update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Ignoring MPD Status Update Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 77ms Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 76ms Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand add "USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf" took 71 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand add "USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf" took 70 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand play Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand play Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CURURI: music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 44ms Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 43ms Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand play took 38 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand play took 37 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CURURI: music-library/USB/移动硬盘/SACD（共1551G）/玉置浩二SACD Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Jun 15 21:42:43 minidsp-shd volumio[2301]: ---------------------------- MPD announces state update: player Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ControllerMpd::getState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand status Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand status took 151 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:42:43 minidsp-shd volumio[2301]: info: Preload queue cleared Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand status took 101 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 92 milliseconds Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseState Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":40,"duration":290,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"To Me","artist":"Koji Tamaki - 玉置浩二","album":"ワインレッドの心 - 酒红色的心","uri":"USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf","trackType":"dsf"} Jun 15 21:42:43 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 0 Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:42:43 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus stop Jun 15 21:42:43 minidsp-shd volumio[2301]: info: ------------------------------ 276ms Jun 15 21:42:44 minidsp-shd volumio[2301]: info: sendMpdCommand playlistinfo took 278 milliseconds Jun 15 21:42:44 minidsp-shd volumio[2301]: verbose: ControllerMpd::parseTrackInfo Jun 15 21:42:44 minidsp-shd volumio[2301]: info: ControllerMpd::pushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::servicePushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:44 minidsp-shd volumio[2301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":423,"duration":290,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"To Me","artist":"Koji Tamaki - 玉置浩二","album":"ワインレッドの心 - 酒红色的心","uri":"USB/移动硬盘/SACD（共1551G）/玉置浩二SACD/玉置浩二 - 酒红色的心DSF/01.TO ME.dsf","trackType":"dsf"} Jun 15 21:42:44 minidsp-shd volumio[2301]: verbose: CURRENT POSITION 0 Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState stateService play Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreStateMachine::syncState currentStatus play Jun 15 21:42:44 minidsp-shd volumio[2301]: info: Received an update from plugin. extracting info from payload Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreStateMachine::pushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CorePlayQueue::getTrack 0 Jun 15 21:42:44 minidsp-shd volumio[2301]: info: CoreCommandRouter::volumioPushState Jun 15 21:42:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output update for this device Jun 15 21:42:44 minidsp-shd volumio[2301]: info: MRS: Pushing multiroomSync output Jun 15 21:42:44 minidsp-shd volumio[2301]: info: ------------------------------ 471ms Jun 15 21:42:46 minidsp-shd volumio[2301]: info: MyVolumio login type: Token Jun 15 21:42:46 minidsp-shd volumio[2301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 21:42:46 minidsp-shd volumio[2301]: Error: connect ETIMEDOUT 199.16.158.104:443 Jun 15 21:42:46 minidsp-shd volumio[2301]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 15 21:42:46 minidsp-shd volumio[2301]: errno: -110, Jun 15 21:42:46 minidsp-shd volumio[2301]: code: 'ETIMEDOUT', Jun 15 21:42:46 minidsp-shd volumio[2301]: syscall: 'connect', Jun 15 21:42:46 minidsp-shd volumio[2301]: address: '199.16.158.104', Jun 15 21:42:46 minidsp-shd volumio[2301]: port: 443 Jun 15 21:42:46 minidsp-shd volumio[2301]: } Jun 15 21:42:46 minidsp-shd volumio[2301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 21:42:47 minidsp-shd sudo[4796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 21:41 Jun 15 21:42:47 minidsp-shd sudo[4796]: 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"