-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Fri 2025-10-24 16:51:15 CST. -- Oct 24 16:50:02 dap10 volumio[998]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 24 16:50:02 dap10 volumio[998]: info: Retrying Login Due to Network Error Oct 24 16:50:07 dap10 volumio[998]: info: Oct 24 16:50:07 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:07 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:07 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:07 dap10 volumio[998]: info: Oct 24 16:50:07 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:07 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:07 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:07 dap10 volumio[998]: info: sendMpdCommand status took 6 milliseconds Oct 24 16:50:07 dap10 volumio[998]: info: sendMpdCommand status took 5 milliseconds Oct 24 16:50:07 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:07 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: 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} Oct 24 16:50:07 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::play index undefined Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:07 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: 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} Oct 24 16:50:07 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:07 dap10 volumio[998]: info: No code Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:07 dap10 volumio[998]: info: ------------------------------ 54ms Oct 24 16:50:07 dap10 volumio[998]: info: ------------------------------ 53ms Oct 24 16:50:07 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:07 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:07 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:12 dap10 volumio[998]: info: MyVolumio login type: Token Oct 24 16:50:34 dap10 volumio[998]: error: Upnp client error: Error: This socket has been ended by the other party Oct 24 16:50:37 dap10 volumio[998]: info: Clearing queue after UPNP request Oct 24 16:50:37 dap10 volumio[998]: info: CoreStateMachine::ClearQueue Oct 24 16:50:37 dap10 volumio[998]: info: CoreStateMachine::stop Oct 24 16:50:37 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:37 dap10 volumio[998]: info: CorePlayQueue::clearPlayQueue Oct 24 16:50:37 dap10 volumio[998]: info: CorePlayQueue::saveQueue Oct 24 16:50:37 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:37 dap10 volumio[998]: info: CoreCommandRouter::volumioPushQueue Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:37 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: Starting UPNP Playback Oct 24 16:50:37 dap10 volumio[998]: info: Preparing playback through UPNP Oct 24 16:50:37 dap10 volumio[998]: info: CoreCommandRouter::volumioGetState Oct 24 16:50:37 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:37 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 8ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 6ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: Oct 24 16:50:37 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:37 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:37 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 13 milliseconds Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 15 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 20ms Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 5 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 2 milliseconds Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:38 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 43ms Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:38 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 31 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:38 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:38 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 67ms Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 64ms Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:38 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:38 dap10 volumio[998]: info: Oct 24 16:50:38 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 48ms Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 39 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"397 Kbps","isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:38 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 54ms Oct 24 16:50:38 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 24 16:50:38 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:38 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:38 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:38 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":244,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"432 Kbps","isStreaming":false,"title":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:38 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:38 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:38 dap10 volumio[998]: info: ------------------------------ 29ms Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:38 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:38 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:42 dap10 volumio[998]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 24 16:50:42 dap10 volumio[998]: info: Retrying Login Due to Network Error Oct 24 16:50:52 dap10 volumio[998]: info: MyVolumio login type: Token Oct 24 16:50:54 dap10 volumio[998]: info: Oct 24 16:50:54 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:54 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:54 dap10 volumio[998]: info: Oct 24 16:50:54 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:54 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:54 dap10 volumio[998]: info: sendMpdCommand status took 2 milliseconds Oct 24 16:50:54 dap10 volumio[998]: info: sendMpdCommand status took 2 milliseconds Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:54 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:54 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:54 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:54 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: 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":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:54 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::play index undefined Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:54 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: 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":"DNA","artist":"Mighty Mo Rodgers","album":"Red, White & Blues","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F0000012Io102l6R5O.flac?guid=00000000523aab8b00000188777bd787&vkey=F42300EADC86AABE6C7A23B0EB8821F36DB06B5A136D7E41E7989BBC099F77AC51E99A1782EFB08E04E0BAD52EE34A0F20891EF4CCB6009F__v2ba83dd5&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:54 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:54 dap10 volumio[998]: info: No code Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:54 dap10 volumio[998]: info: ------------------------------ 44ms Oct 24 16:50:54 dap10 volumio[998]: info: ------------------------------ 44ms Oct 24 16:50:54 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:54 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:54 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:54 dap10 volumio[998]: info: Clearing queue after UPNP request Oct 24 16:50:55 dap10 volumio[998]: info: CoreStateMachine::ClearQueue Oct 24 16:50:55 dap10 volumio[998]: info: CoreStateMachine::stop Oct 24 16:50:55 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:55 dap10 volumio[998]: info: CorePlayQueue::clearPlayQueue Oct 24 16:50:55 dap10 volumio[998]: info: CorePlayQueue::saveQueue Oct 24 16:50:55 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:55 dap10 volumio[998]: info: CoreCommandRouter::volumioPushQueue Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:55 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:55 dap10 volumio[998]: info: Starting UPNP Playback Oct 24 16:50:55 dap10 volumio[998]: info: Preparing playback through UPNP Oct 24 16:50:55 dap10 volumio[998]: info: CoreCommandRouter::volumioGetState Oct 24 16:50:55 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:55 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:55 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:55 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:55 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:55 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:55 dap10 volumio[998]: info: Oct 24 16:50:55 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:55 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:55 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:56 dap10 volumio[998]: info: Oct 24 16:50:56 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:56 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:56 dap10 volumio[998]: info: Oct 24 16:50:56 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand status took 41 milliseconds Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand status took 40 milliseconds Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 6ms Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand status took 5 milliseconds Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:56 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:56 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:56 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:56 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:56 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1028 Kbps","isStreaming":false,"title":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:56 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:56 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 61ms Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 69ms Oct 24 16:50:56 dap10 volumio[998]: info: Oct 24 16:50:56 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:56 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:56 dap10 volumio[998]: info: Oct 24 16:50:56 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:56 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:56 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1055 Kbps","isStreaming":false,"title":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:56 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:56 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 49ms Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 24 16:50:56 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:56 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:56 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:56 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":274,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1055 Kbps","isStreaming":false,"title":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:56 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:56 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:56 dap10 volumio[998]: info: ------------------------------ 37ms Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:56 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:56 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:57 dap10 volumio[998]: info: Oct 24 16:50:57 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:57 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:57 dap10 volumio[998]: info: Oct 24 16:50:57 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:57 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:57 dap10 volumio[998]: info: sendMpdCommand status took 2 milliseconds Oct 24 16:50:57 dap10 volumio[998]: info: sendMpdCommand status took 2 milliseconds Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:57 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:57 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: 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":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:57 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::play index undefined Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: info: ------------------------------ 31ms Oct 24 16:50:57 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 24 16:50:57 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:57 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: 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":"Isabella","artist":"ozielzinho","album":"A Procura","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000002WyO0G33LT0R.flac?guid=00000000523aab8b00000188777bd787&vkey=279C879F33FA34C8F2AFE7E271688B032611CA85CE5CA2EECAAABB216F8006BE2159CCF1A932CE321AF6BCD25CDD21BB9902E7B9F0A889D6__v2b94c04b&uin=1152921504798975326&src=F000004Vp51S32V6EL.flac&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:57 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::syncState stateService stop Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:57 dap10 volumio[998]: info: No code Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:57 dap10 volumio[998]: info: ------------------------------ 60ms Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:57 dap10 volumio[998]: info: Clearing queue after UPNP request Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::ClearQueue Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::stop Oct 24 16:50:57 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::clearPlayQueue Oct 24 16:50:57 dap10 volumio[998]: info: CorePlayQueue::saveQueue Oct 24 16:50:57 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushQueue Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:58 dap10 volumio[998]: error: updateQueue error: null Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:58 dap10 volumio[998]: info: Starting UPNP Playback Oct 24 16:50:58 dap10 volumio[998]: info: Preparing playback through UPNP Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetState Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getTrack 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 9ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 10ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 3ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 2ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 30 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 32 milliseconds Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus stop Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 38ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 7 milliseconds Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:58 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 64ms Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:58 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:58 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 72ms Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 67ms Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces system playlist update Oct 24 16:50:58 dap10 volumio[998]: info: Ignoring MPD Status Update Oct 24 16:50:58 dap10 volumio[998]: info: Oct 24 16:50:58 dap10 volumio[998]: ---------------------------- MPD announces state update: player Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::getState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand status Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 50ms Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 48 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 4ms Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand status took 3 milliseconds Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseState Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"825 Kbps","isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:58 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 69ms Oct 24 16:50:58 dap10 volumio[998]: info: sendMpdCommand playlistinfo took 18 milliseconds Oct 24 16:50:58 dap10 volumio[998]: verbose: ControllerMpd::parseTrackInfo Oct 24 16:50:58 dap10 volumio[998]: info: ControllerMpd::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::servicePushState Oct 24 16:50:58 dap10 volumio[998]: verbose: In UPNP mode Oct 24 16:50:58 dap10 volumio[998]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"Starlight","artist":"Yola","album":"Starlight","uri":"http://14.152.68.36/amobile.music.tc.qq.com/F000003KGlK70nJMi4.flac?guid=00000000523aab8b00000188777bd787&vkey=F5E4A6CB86B94FDBD28B58092C5D885C0E8DA866ECE076012DDCFA25FAD35F766FF1E234F302F1D3FEE7C5343AE053EA8DD21A0AE78BD265__v21e2a1f9e&uin=1152921504798975326&redirect=1&fromtag=111114","trackType":""} Oct 24 16:50:58 dap10 volumio[998]: verbose: CURRENT POSITION 0 Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState stateService play Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::syncState currentStatus play Oct 24 16:50:58 dap10 volumio[998]: info: Received an update from plugin. extracting info from payload Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::pushState Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioPushState Oct 24 16:50:58 dap10 volumio[998]: info: ------------------------------ 37ms Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreCommandRouter::volumioGetQueue Oct 24 16:50:58 dap10 volumio[998]: info: CoreStateMachine::getQueue Oct 24 16:50:58 dap10 volumio[998]: info: CorePlayQueue::getQueue Oct 24 16:51:15 dap10 volumio[998]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 16:51:15 dap10 volumio[998]: Error: read ETIMEDOUT Oct 24 16:51:15 dap10 volumio[998]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { Oct 24 16:51:15 dap10 volumio[998]: errno: -110, Oct 24 16:51:15 dap10 volumio[998]: code: 'ETIMEDOUT', Oct 24 16:51:15 dap10 volumio[998]: syscall: 'read' Oct 24 16:51:15 dap10 volumio[998]: } Oct 24 16:51:15 dap10 volumio[998]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 16:51:15 dap10 sudo[5061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 16:50 Oct 24 16:51:15 dap10 sudo[5061]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"