-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Tue 2025-12-16 18:00:20 CST. -- Dec 16 17:59:00 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:00 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:00 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:00 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:00 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:00 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:00 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:00 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:00 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:00 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:00 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:00 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:00 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:00 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:00 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: Dec 16 17:59:00 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:00 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:00 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 11 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 15 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 3 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 25ms Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 9 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:01 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:01 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:01 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:01 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 207ms Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 197ms Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 198ms Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:01 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:01 volumio-best volumio[992]: info: Dec 16 17:59:01 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 191ms Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 189 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand status took 6 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 116 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 118 milliseconds Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:01 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:01 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:01 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":211,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:01 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:01 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 502ms Dec 16 17:59:01 volumio-best volumio[992]: info: ------------------------------ 321ms Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:01 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:02 volumio-best volumio[992]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 16 17:59:02 volumio-best volumio[992]: info: Retrying Login Due to Network Error Dec 16 17:59:03 volumio-best volumio[992]: info: Dec 16 17:59:03 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:03 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:03 volumio-best volumio[992]: info: Dec 16 17:59:03 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:03 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:03 volumio-best volumio[992]: info: sendMpdCommand status took 3 milliseconds Dec 16 17:59:03 volumio-best volumio[992]: info: sendMpdCommand status took 1 milliseconds Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:03 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 17:59:03 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:03 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:03 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: 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":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:03 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:03 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: 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":"唯一的偏爱","artist":"en (王翊恩)","album":"唯一的偏爱","uri":"https://aqqmusic.tc.qq.com/M500004ZGDAQ1Jl6tq.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=656358539EC201876FD0DBE0E3A895CCBA602BB65E2742602FEE3C7CEC1807D0FD230D88263DA7111D4EA3C3F93CE771B84814F0431E77E0__v2b9ab1ef&uin=1152921504881653458&src=M500000spo9B0vtQcO.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:03 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:03 volumio-best volumio[992]: info: No code Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:03 volumio-best volumio[992]: info: ------------------------------ 113ms Dec 16 17:59:03 volumio-best volumio[992]: info: ------------------------------ 115ms Dec 16 17:59:03 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:03 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:03 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:03 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:03 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:04 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:04 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:04 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:04 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:04 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:04 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 1ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 19 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 18 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 13 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 8 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:04 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:04 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:04 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:04 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:04 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:04 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:04 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 77ms Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 117ms Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:04 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:04 volumio-best volumio[992]: info: Dec 16 17:59:04 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 94ms Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 95 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 86 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: info: ------------------------------ 13ms Dec 16 17:59:04 volumio-best volumio[992]: info: sendMpdCommand status took 12 milliseconds Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:04 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:04 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:04 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:04 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:04 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:04 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:04 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:04 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:05 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:05 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:05 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:05 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: ------------------------------ 285ms Dec 16 17:59:05 volumio-best volumio[992]: info: ------------------------------ 276ms Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 301 milliseconds Dec 16 17:59:05 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:05 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:05 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:05 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:05 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:05 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:05 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:05 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:05 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":242,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:05 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:05 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:05 volumio-best volumio[992]: info: ------------------------------ 606ms Dec 16 17:59:05 volumio-best volumio[992]: info: ------------------------------ 521ms Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:05 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:06 volumio-best volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 11 Dec 16 17:59:06 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:06 volumio-best volumio[992]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 12 Dec 16 17:59:06 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:09 volumio-best volumio[992]: info: Dec 16 17:59:09 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:09 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:09 volumio-best volumio[992]: info: Dec 16 17:59:09 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:09 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:09 volumio-best volumio[992]: info: sendMpdCommand status took 3 milliseconds Dec 16 17:59:09 volumio-best volumio[992]: info: sendMpdCommand status took 1 milliseconds Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:09 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:09 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:09 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:09 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: 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":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:09 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: 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":"阳光下的星星","artist":"颜人中/王赫野","album":"声生不息·华流季 第4期","uri":"https://aqqmusic.tc.qq.com/M500003l4YJ80LIhNS.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=E1BA9AACD7BC7DABD3DF5D551A5B6E6AC70CC39F70561A2E07F41D4F8BDA9E6C7FC6F00B1611744EF4421F5EA6D236EA091C85F757923C1A__v2b9aae1a&uin=1152921504881653458&src=M500002ZP39H0OCu8d.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:09 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: No code Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: ------------------------------ 106ms Dec 16 17:59:09 volumio-best volumio[992]: info: ------------------------------ 107ms Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:09 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:09 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:09 volumio-best volumio[992]: info: Dec 16 17:59:09 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:09 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:09 volumio-best volumio[992]: info: Dec 16 17:59:09 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:09 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:09 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:09 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:09 volumio-best volumio[992]: info: ------------------------------ 9ms Dec 16 17:59:09 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:09 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:09 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:09 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:09 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:09 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 11ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 10ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 1ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 16 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 18 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 4 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 34ms Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:10 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:10 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 183ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 171ms Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 165 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 165ms Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 164 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:10 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:10 volumio-best volumio[992]: info: Dec 16 17:59:10 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 137 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 138 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 138ms Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand status took 139 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:10 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:10 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 420ms Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 419ms Dec 16 17:59:10 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 114 milliseconds Dec 16 17:59:10 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:10 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:10 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:10 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:10 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:10 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:10 volumio-best volumio[992]: info: ------------------------------ 328ms Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:10 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:12 volumio-best volumio[992]: info: MyVolumio login type: Token Dec 16 17:59:14 volumio-best volumio[992]: info: Dec 16 17:59:14 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:14 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:14 volumio-best volumio[992]: info: Dec 16 17:59:14 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:14 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:14 volumio-best volumio[992]: info: sendMpdCommand status took 5 milliseconds Dec 16 17:59:14 volumio-best volumio[992]: info: sendMpdCommand status took 4 milliseconds Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:14 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 17:59:14 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:14 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:14 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: 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":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:14 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:14 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: 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":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:14 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:14 volumio-best volumio[992]: info: No code Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:14 volumio-best volumio[992]: info: ------------------------------ 110ms Dec 16 17:59:14 volumio-best volumio[992]: info: ------------------------------ 110ms Dec 16 17:59:14 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:14 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:14 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:14 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:17 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:17 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:17 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:17 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:17 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:17 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:17 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:17 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:17 volumio-best volumio[992]: info: Dec 16 17:59:17 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:17 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:17 volumio-best volumio[992]: info: Dec 16 17:59:17 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:17 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:17 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:17 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:17 volumio-best volumio[992]: info: ------------------------------ 7ms Dec 16 17:59:17 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:17 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:17 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:17 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:17 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:17 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 5ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 14 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 13 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 9 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 11 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:18 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:18 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 62ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 131ms Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 122ms Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:18 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:18 volumio-best volumio[992]: info: Dec 16 17:59:18 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 117ms Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 116 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 114 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 9ms Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:18 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 159ms Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand status took 157 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 152 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:18 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 300ms Dec 16 17:59:18 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 16 17:59:18 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:18 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:18 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:18 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":230,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:18 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:18 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:18 volumio-best volumio[992]: info: ------------------------------ 223ms Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:18 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:19 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:23 volumio-best volumio[992]: info: Dec 16 17:59:23 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:23 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:23 volumio-best volumio[992]: info: Dec 16 17:59:23 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:23 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:23 volumio-best volumio[992]: info: sendMpdCommand status took 11 milliseconds Dec 16 17:59:23 volumio-best volumio[992]: info: sendMpdCommand status took 11 milliseconds Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:23 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 17:59:23 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:23 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:23 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: 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":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:23 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:23 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: 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":"白雪风吹","artist":"花花兔","album":"白雪风吹","uri":"https://aqqmusic.tc.qq.com/M500000bmXpT2rXONN.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=77B43B8A58B9429A5301D3B6BEBDAC53B152151AD56BFD4D4454E05B27017CC4F436A7F8A7E66F7750584EC2547C06E5A4C00F59317DD8AE__v2b9ab1f4&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:23 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:23 volumio-best volumio[992]: info: No code Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:23 volumio-best volumio[992]: info: ------------------------------ 50ms Dec 16 17:59:23 volumio-best volumio[992]: info: ------------------------------ 52ms Dec 16 17:59:23 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:23 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:23 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:23 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:29 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:29 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:29 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:29 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:29 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:29 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:29 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:29 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:29 volumio-best volumio[992]: info: Dec 16 17:59:29 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:29 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:29 volumio-best volumio[992]: info: Dec 16 17:59:29 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:30 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:30 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:30 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:30 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:30 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:30 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 6ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 1ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: Dec 16 17:59:30 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:30 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:30 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 12 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 13 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 5 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 2 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:32 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 45ms Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 51ms Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:32 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:32 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:32 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 85ms Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 81ms Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:32 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:32 volumio-best volumio[992]: info: Dec 16 17:59:32 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 185ms Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 182 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 142ms Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand status took 140 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:32 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:32 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:32 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":244,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:32 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:32 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 266ms Dec 16 17:59:32 volumio-best volumio[992]: info: ------------------------------ 222ms Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:32 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:42 volumio-best volumio[992]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 16 17:59:42 volumio-best volumio[992]: info: Retrying Login Due to Network Error Dec 16 17:59:52 volumio-best volumio[992]: info: MyVolumio login type: Token Dec 16 17:59:54 volumio-best volumio[992]: info: Dec 16 17:59:54 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:54 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:54 volumio-best volumio[992]: info: Dec 16 17:59:54 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:54 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:54 volumio-best volumio[992]: info: sendMpdCommand status took 3 milliseconds Dec 16 17:59:54 volumio-best volumio[992]: info: sendMpdCommand status took 2 milliseconds Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:54 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 17:59:54 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:54 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:54 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: 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":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:54 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:54 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: 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":"碎糖霜","artist":"幻纱","album":"多元幻想曲","uri":"https://aqqmusic.tc.qq.com/M500001PHXBH2mHktx.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=F5EA2D8679E855DC45E655D62E0FF944C5A0D437D4A0A94204FF4D3A303D35680B7D1EA14223D9ADB15BCC57E7C03E6AB833241BF8EC50E7__v2b9aae1a&uin=1152921504881653458&src=M500000nlA533Okqd7.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:54 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:54 volumio-best volumio[992]: info: No code Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:54 volumio-best volumio[992]: info: ------------------------------ 56ms Dec 16 17:59:54 volumio-best volumio[992]: info: ------------------------------ 56ms Dec 16 17:59:54 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:54 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:54 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:55 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 17:59:55 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 17:59:55 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 17:59:55 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 17:59:55 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:55 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 17:59:55 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 17:59:55 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:55 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: error: updateQueue error: null Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:55 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 17:59:55 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 17:59:55 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 17:59:55 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:55 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: Dec 16 17:59:55 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:55 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 17:59:55 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 12 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 11 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 5 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:56 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 34ms Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 39ms Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 24 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:56 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 74ms Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:56 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 161 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:56 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 222ms Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 17:59:56 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 17:59:56 volumio-best volumio[992]: info: Dec 16 17:59:56 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 69ms Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 65 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 33ms Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand status took 30 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:56 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":822,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:56 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:56 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 17:59:56 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":197,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:56 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:56 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 145ms Dec 16 17:59:56 volumio-best volumio[992]: info: ------------------------------ 109ms Dec 16 17:59:56 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:56 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:59 volumio-best volumio[992]: info: Dec 16 17:59:59 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:59 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:59 volumio-best volumio[992]: info: Dec 16 17:59:59 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 17:59:59 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 17:59:59 volumio-best volumio[992]: info: sendMpdCommand status took 3 milliseconds Dec 16 17:59:59 volumio-best volumio[992]: info: sendMpdCommand status took 1 milliseconds Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 17:59:59 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 16 17:59:59 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:59 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 17:59:59 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: 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":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:59 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::play index undefined Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:59 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: 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":"秋蛊","artist":"舒儿Gogo","album":"秋蛊","uri":"https://aqqmusic.tc.qq.com/M5000012hC9W1aAG8x.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=4DBC4315C1BE6DAC2C247E10E3CB6ED2B8635C0D8C10A777AC00BAAE3B6918538D6FDE4C159E7D71FD02F6E9E33C82A48BE3E8DFA4BA0F8F__v2b9aac97&uin=1152921504881653458&redirect=1&fromtag=111042","trackType":""} Dec 16 17:59:59 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService stop Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:59 volumio-best volumio[992]: info: No code Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 17:59:59 volumio-best volumio[992]: info: ------------------------------ 41ms Dec 16 17:59:59 volumio-best volumio[992]: info: ------------------------------ 43ms Dec 16 17:59:59 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 17:59:59 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 17:59:59 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 17:59:59 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 60 seconds. Dec 16 18:00:12 volumio-best volumio[992]: info: Clearing queue after UPNP request Dec 16 18:00:13 volumio-best volumio[992]: info: CoreStateMachine::ClearQueue Dec 16 18:00:13 volumio-best volumio[992]: info: CoreStateMachine::stop Dec 16 18:00:13 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 18:00:13 volumio-best volumio[992]: info: CorePlayQueue::clearPlayQueue Dec 16 18:00:13 volumio-best volumio[992]: info: CorePlayQueue::saveQueue Dec 16 18:00:13 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:13 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushQueue Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: error: updateQueue error: null Dec 16 18:00:13 volumio-best volumio[992]: error: updateQueue error: null Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: Starting UPNP Playback Dec 16 18:00:13 volumio-best volumio[992]: info: Preparing playback through UPNP Dec 16 18:00:13 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetState Dec 16 18:00:13 volumio-best volumio[992]: info: CorePlayQueue::getTrack 0 Dec 16 18:00:13 volumio-best volumio[992]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 2ms Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: Dec 16 18:00:13 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:13 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 4ms Dec 16 18:00:13 volumio-best volumio[992]: info: ------------------------------ 3ms Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 9 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 13 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 4 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 2 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:15 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:15 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:15 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus stop Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 26ms Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:15 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:15 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:15 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 18:00:15 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:15 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:15 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:15 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 18:00:15 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:15 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:15 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:15 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 18:00:15 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 102ms Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 101ms Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 97ms Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:15 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces system playlist update Dec 16 18:00:15 volumio-best volumio[992]: info: Ignoring MPD Status Update Dec 16 18:00:15 volumio-best volumio[992]: info: Dec 16 18:00:15 volumio-best volumio[992]: ---------------------------- MPD announces state update: player Dec 16 18:00:15 volumio-best volumio[992]: info: ControllerMpd::getState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand status Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 283ms Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 281 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: info: ------------------------------ 132ms Dec 16 18:00:15 volumio-best volumio[992]: info: sendMpdCommand status took 102 milliseconds Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::parseState Dec 16 18:00:15 volumio-best volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 18:00:16 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 64 milliseconds Dec 16 18:00:16 volumio-best volumio[992]: info: sendMpdCommand playlistinfo took 64 milliseconds Dec 16 18:00:16 volumio-best volumio[992]: verbose: ControllerMpd::parseTrackInfo Dec 16 18:00:16 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:16 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:16 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:16 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 18:00:16 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:16 volumio-best volumio[992]: info: ControllerMpd::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::servicePushState Dec 16 18:00:16 volumio-best volumio[992]: verbose: In UPNP mode Dec 16 18:00:16 volumio-best volumio[992]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":187,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"晚风心里吹","artist":"阿梨粤","album":"晚风心里吹","uri":"https://aqqmusic.tc.qq.com/M500000VQhIC3fZvwC.mp3?guid=000000002d9016fb000001897cb3efd7&vkey=986AF88F1AA982C97C0FD6CF7EBECB05EB8488BDB0E825AB636F3A12A516309A51F985D6ADC48744C4E8D98BA1642159946BCD2393E4CE90__v2b94c00e&uin=1152921504881653458&src=M500000ryj6q0zY6zP.mp3&redirect=1&fromtag=111042","trackType":""} Dec 16 18:00:16 volumio-best volumio[992]: verbose: CURRENT POSITION 0 Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::syncState stateService play Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::syncState currentStatus play Dec 16 18:00:16 volumio-best volumio[992]: info: Received an update from plugin. extracting info from payload Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::pushState Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioPushState Dec 16 18:00:16 volumio-best volumio[992]: info: ------------------------------ 381ms Dec 16 18:00:16 volumio-best volumio[992]: info: ------------------------------ 209ms Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreCommandRouter::volumioGetQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CoreStateMachine::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: CorePlayQueue::getQueue Dec 16 18:00:16 volumio-best volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Dec 16 18:00:18 volumio-best volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 18:00:18 volumio-best volumio[992]: Error: connect ECONNREFUSED 93.179.102.140:443 Dec 16 18:00:18 volumio-best volumio[992]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 16 18:00:18 volumio-best volumio[992]: errno: -111, Dec 16 18:00:18 volumio-best volumio[992]: code: 'ECONNREFUSED', Dec 16 18:00:18 volumio-best volumio[992]: syscall: 'connect', Dec 16 18:00:18 volumio-best volumio[992]: address: '93.179.102.140', Dec 16 18:00:18 volumio-best volumio[992]: port: 443 Dec 16 18:00:18 volumio-best volumio[992]: } Dec 16 18:00:18 volumio-best volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 18:00:20 volumio-best sudo[5270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 17:59 Dec 16 18:00:20 volumio-best sudo[5270]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"