-- Logs begin at Thu 2019-02-14 13:12:01 MSK, end at Fri 2024-05-03 20:20:57 MSK. -- May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioNext May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::next May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand stop took 46 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::play index undefined May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::startPlaybackTimer May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand status took 23 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand stop took 11 milliseconds May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 34 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand clear took 34 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand status took 27 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand status took 21 milliseconds May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac" May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Great Plains","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/01 - The Great Plains.flac","trackType":"flac"} May 03 20:19:18 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: No code May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:19:18 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: No code May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:19:18 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: No code May 03 20:19:18 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:18 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:18 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 609ms May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 571ms May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 568ms May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:18 volumioopiz volumio[916]: error: updateQueue error: null May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 601ms May 03 20:19:18 volumioopiz volumio[916]: info: sendMpdCommand add "NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac" took 586 milliseconds May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 45ms May 03 20:19:18 volumioopiz volumio[916]: info: ------------------------------ 39ms May 03 20:19:18 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:18 volumioopiz volumio[916]: info: May 03 20:19:18 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:18 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 256ms May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand play took 245 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 241ms May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 237ms May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 43 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 36 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 27 milliseconds May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: May 03 20:19:19 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 548 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 537 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 536 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 535 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 519 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand status took 510 milliseconds May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1178 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:19 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1178 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:19 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:19 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1178 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:19 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:19 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 736ms May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 830ms May 03 20:19:19 volumioopiz volumio[916]: info: ------------------------------ 825ms May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 265 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 260 milliseconds May 03 20:19:19 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 259 milliseconds May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:19 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1178 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:19 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:19 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:19 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:19 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:19 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1178 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:20 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:20 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":280,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1182 Kbps","isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:20 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:20 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:20 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:20 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:20 volumioopiz volumio[916]: info: ------------------------------ 1151ms May 03 20:19:20 volumioopiz volumio[916]: info: ------------------------------ 1118ms May 03 20:19:20 volumioopiz volumio[916]: info: ------------------------------ 1110ms May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioNext May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::next May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:21 volumioopiz volumio[916]: info: May 03 20:19:21 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand stop took 49 milliseconds May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::play index undefined May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::startPlaybackTimer May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand status took 22 milliseconds May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand stop took 10 milliseconds May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 03 20:19:21 volumioopiz volumio[916]: info: May 03 20:19:21 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:21 volumioopiz volumio[916]: info: May 03 20:19:21 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:21 volumioopiz volumio[916]: info: May 03 20:19:21 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:21 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 26 milliseconds May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand clear took 25 milliseconds May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand status took 21 milliseconds May 03 20:19:21 volumioopiz volumio[916]: info: sendMpdCommand status took 14 milliseconds May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac" May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:21 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Panoramic","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/02 - Panoramic.flac","trackType":"flac"} May 03 20:19:21 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: No code May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:19:21 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: No code May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:19:21 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:21 volumioopiz volumio[916]: info: No code May 03 20:19:21 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:21 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:21 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 490ms May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 456ms May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 455ms May 03 20:19:22 volumioopiz volumio[916]: info: May 03 20:19:22 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:22 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:22 volumioopiz volumio[916]: info: May 03 20:19:22 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:22 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:22 volumioopiz volumio[916]: error: updateQueue error: null May 03 20:19:22 volumioopiz volumio[916]: info: May 03 20:19:22 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:22 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 486ms May 03 20:19:22 volumioopiz volumio[916]: info: sendMpdCommand add "NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac" took 472 milliseconds May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 37ms May 03 20:19:22 volumioopiz volumio[916]: info: ------------------------------ 32ms May 03 20:19:22 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 03 20:19:22 volumioopiz volumio[916]: info: May 03 20:19:22 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:22 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:22 volumioopiz volumio[916]: info: May 03 20:19:22 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:22 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 796ms May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand play took 785 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 783ms May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 778ms May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 40 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 34 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 28 milliseconds May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: May 03 20:19:23 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 43 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 34 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 33 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 33 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 24 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand status took 18 milliseconds May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1263 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1485,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1282 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:23 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1283 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:23 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 229ms May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 323ms May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 320ms May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 250 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 245 milliseconds May 03 20:19:23 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 244 milliseconds May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1258 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:23 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1258 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:23 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1611,"duration":203,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1258 Kbps","isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:23 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:19:23 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:23 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:23 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 624ms May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 600ms May 03 20:19:23 volumioopiz volumio[916]: info: ------------------------------ 597ms May 03 20:19:38 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 20:19:38 volumioopiz volumio[916]: info: CURURI: music-library/NAS/DS713/Montefiori Cocktail May 03 20:19:39 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:19:43 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 20:19:43 volumioopiz volumio[916]: info: CURURI: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA) May 03 20:19:43 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/08 - Slow Blues In 'C'.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/13 - Going Home.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/14 - Rip It Up.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/07 - I'm Writing You A Letter.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/10 - Scat Encounter.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/12 - Love Like A Man.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/06 - I Don't Give A Damn.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/11 - I Woke Up This Morning.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/05 - Country Thing.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/09 - I'm Gonna Make It.flac May 03 20:19:43 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/04 - My Baby Left Me.flac May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/08 - Slow Blues In 'C'.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/13 - Going Home.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/14 - Rip It Up.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/07 - I'm Writing You A Letter.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/10 - Scat Encounter.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/12 - Love Like A Man.flac in service mpd May 03 20:19:45 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/06 - I Don't Give A Damn.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/11 - I Woke Up This Morning.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/05 - Country Thing.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/09 - I'm Gonna Make It.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/04 - My Baby Left Me.flac in service mpd May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F08%20-%20Slow%20Blues%20In%20'C'.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/08 - Slow Blues In 'C'.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F03%20-%20How%20Do%20You%20Do%20It.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F13%20-%20Going%20Home.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/13 - Going Home.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F14%20-%20Rip%20It%20Up.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/14 - Rip It Up.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F07%20-%20I'm%20Writing%20You%20A%20Letter.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/07 - I'm Writing You A Letter.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F10%20-%20Scat%20Encounter.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/10 - Scat Encounter.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F01%20-%20Hear%20Me%20Calling.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F12%20-%20Love%20Like%20A%20Man.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/12 - Love Like A Man.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F06%20-%20I%20Don't%20Give%20A%20Damn.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/06 - I Don't Give A Damn.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F02%20-%20I%20Can't%20Keep%20From%20Crying%20Sometimes.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F11%20-%20I%20Woke%20Up%20This%20Morning.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/11 - I Woke Up This Morning.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F05%20-%20Country%20Thing.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/05 - Country Thing.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F09%20-%20I'm%20Gonna%20Make%20It.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/09 - I'm Gonna Make It.flac May 03 20:19:46 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F04%20-%20My%20Baby%20Left%20Me.flac&metadata=false May 03 20:19:46 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/04 - My Baby Left Me.flac May 03 20:19:46 volumioopiz volumio[916]: Upnp client error: Error: This socket has been ended by the other party May 03 20:19:58 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::ClearQueue May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:19:58 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::clearPlayQueue May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::saveQueue May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushQueue May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::addQueueItems May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::addQueueItems May 03 20:19:58 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:19:58 volumioopiz volumio[916]: info: Adding Item to queue: music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA) May 03 20:19:58 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA) in service mpd May 03 20:19:58 volumioopiz volumio[916]: info: May 03 20:19:58 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F01%20-%20Hear%20Me%20Calling.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F02%20-%20I%20Can't%20Keep%20From%20Crying%20Sometimes.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F03%20-%20How%20Do%20You%20Do%20It.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F04%20-%20My%20Baby%20Left%20Me.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/04 - My Baby Left Me.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F05%20-%20Country%20Thing.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/05 - Country Thing.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F06%20-%20I%20Don't%20Give%20A%20Damn.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/06 - I Don't Give A Damn.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F07%20-%20I'm%20Writing%20You%20A%20Letter.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/07 - I'm Writing You A Letter.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F08%20-%20Slow%20Blues%20In%20'C'.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/08 - Slow Blues In 'C'.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F09%20-%20I'm%20Gonna%20Make%20It.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/09 - I'm Gonna Make It.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F10%20-%20Scat%20Encounter.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/10 - Scat Encounter.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F11%20-%20I%20Woke%20Up%20This%20Morning.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/11 - I Woke Up This Morning.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F12%20-%20Love%20Like%20A%20Man.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/12 - Love Like A Man.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F13%20-%20Going%20Home.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/13 - Going Home.flac May 03 20:19:58 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Alvin%20Lee/The%20Last%20Show/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FAlvin%20Lee%20-%202013%20-%20The%20Last%20Show%20(Rianman%20RM13022%20USA)%2F14%20-%20Rip%20It%20Up.flac&metadata=false May 03 20:19:58 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/14 - Rip It Up.flac May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand stop took 446 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushQueue May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::saveQueue May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:19:58 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPlay May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::play index 0 May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::play index undefined May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:58 volumioopiz volumio[916]: info: CoreStateMachine::startPlaybackTimer May 03 20:19:58 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:19:58 volumioopiz volumio[916]: info: May 03 20:19:58 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:58 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:58 volumioopiz volumio[916]: info: May 03 20:19:58 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:19:58 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand status took 95 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand stop took 29 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand status took 22 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand status took 15 milliseconds May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:19:58 volumioopiz volumio[916]: info: May 03 20:19:58 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:58 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 38 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand clear took 39 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 39 milliseconds May 03 20:19:58 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 39 milliseconds May 03 20:19:58 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:59 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac" May 03 20:19:59 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:59 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:19:59 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:19:59 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:19:59 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:59 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:59 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:19:59 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:59 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:59 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Manitoba","artist":"Random Forest","album":"Panoramic","uri":"NAS/DS713/Random Forest/2016 - Panoramic [24bit FLAC]/03 - Manitoba.flac","trackType":"flac"} May 03 20:19:59 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:19:59 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:19:59 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:19:59 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:59 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:59 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:59 volumioopiz volumio[916]: info: No code May 03 20:19:59 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:19:59 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:19:59 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:19:59 volumioopiz volumio[916]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 408ms May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 266ms May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 259ms May 03 20:19:59 volumioopiz volumio[916]: info: May 03 20:19:59 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:59 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:59 volumioopiz volumio[916]: info: May 03 20:19:59 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:59 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:59 volumioopiz volumio[916]: error: updateQueue error: null May 03 20:19:59 volumioopiz volumio[916]: info: May 03 20:19:59 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:59 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 273ms May 03 20:19:59 volumioopiz volumio[916]: info: sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac" took 257 milliseconds May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 71ms May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 66ms May 03 20:19:59 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 03 20:19:59 volumioopiz volumio[916]: info: May 03 20:19:59 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:59 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:59 volumioopiz volumio[916]: info: May 03 20:19:59 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:19:59 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 53ms May 03 20:19:59 volumioopiz volumio[916]: info: sendMpdCommand play took 42 milliseconds May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 26ms May 03 20:19:59 volumioopiz volumio[916]: info: ------------------------------ 22ms May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 36 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 30 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 24 milliseconds May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: May 03 20:20:00 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 57 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 48 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 48 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 47 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 66 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand status took 61 milliseconds May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"634 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"717 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:00 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"717 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:00 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 274ms May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 426ms May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 423ms May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 314 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 308 milliseconds May 03 20:20:00 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 307 milliseconds May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"652 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:00 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"652 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:00 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1715,"duration":323,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"652 Kbps","isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:00 volumioopiz volumio[916]: verbose: CURRENT POSITION 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:00 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:00 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:00 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 762ms May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 737ms May 03 20:20:00 volumioopiz volumio[916]: info: ------------------------------ 733ms May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioNext May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::next May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 0 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand stop took 40 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::play index undefined May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::startPlaybackTimer May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand status took 17 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand stop took 7 milliseconds May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 38 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand clear took 38 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand status took 32 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand status took 26 milliseconds May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac" May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hear Me Calling","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/01 - Hear Me Calling.flac","trackType":"flac"} May 03 20:20:06 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: No code May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:20:06 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: No code May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:20:06 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: No code May 03 20:20:06 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:06 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:06 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 490ms May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 463ms May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 460ms May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:06 volumioopiz volumio[916]: error: updateQueue error: null May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 475ms May 03 20:20:06 volumioopiz volumio[916]: info: sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac" took 463 milliseconds May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 57ms May 03 20:20:06 volumioopiz volumio[916]: info: ------------------------------ 53ms May 03 20:20:06 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:06 volumioopiz volumio[916]: info: May 03 20:20:06 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:06 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ------------------------------ 214ms May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand play took 182 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: ------------------------------ 181ms May 03 20:20:07 volumioopiz volumio[916]: info: ------------------------------ 178ms May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 43 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 30 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 23 milliseconds May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: May 03 20:20:07 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 608 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 633 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 634 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 633 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 622 milliseconds May 03 20:20:07 volumioopiz volumio[916]: info: sendMpdCommand status took 616 milliseconds May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:07 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:07 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:07 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:07 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:07 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:07 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:07 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:07 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:07 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:07 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:07 volumioopiz volumio[916]: info: ------------------------------ 817ms May 03 20:20:08 volumioopiz volumio[916]: info: ------------------------------ 902ms May 03 20:20:08 volumioopiz volumio[916]: info: ------------------------------ 891ms May 03 20:20:08 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 232 milliseconds May 03 20:20:08 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 227 milliseconds May 03 20:20:08 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 228 milliseconds May 03 20:20:08 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:08 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:08 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:08 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:08 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:08 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:08 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:08 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":663,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:08 volumioopiz volumio[916]: verbose: CURRENT POSITION 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:08 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:08 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:08 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:08 volumioopiz volumio[916]: info: ------------------------------ 1181ms May 03 20:20:08 volumioopiz volumio[916]: info: ------------------------------ 1155ms May 03 20:20:08 volumioopiz volumio[916]: info: ------------------------------ 1151ms May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioNext May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::next May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 1 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:20:11 volumioopiz volumio[916]: info: May 03 20:20:11 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand stop took 72 milliseconds May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::play index undefined May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::startPlaybackTimer May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::clearAddPlayTracks NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand status took 19 milliseconds May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand stop took 9 milliseconds May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand clear May 03 20:20:11 volumioopiz volumio[916]: info: May 03 20:20:11 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:11 volumioopiz volumio[916]: info: May 03 20:20:11 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:11 volumioopiz volumio[916]: info: May 03 20:20:11 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:11 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 55 milliseconds May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand clear took 56 milliseconds May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand status took 50 milliseconds May 03 20:20:11 volumioopiz volumio[916]: info: sendMpdCommand status took 43 milliseconds May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac" May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:11 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Can't Keep From Crying Sometimes","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/02 - I Can't Keep From Crying Sometimes.flac","trackType":"flac"} May 03 20:20:11 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: No code May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:20:11 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: No code May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 03 20:20:11 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:11 volumioopiz volumio[916]: info: No code May 03 20:20:11 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:11 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:11 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 534ms May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 486ms May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 482ms May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:12 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:12 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:12 volumioopiz volumio[916]: error: updateQueue error: null May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:12 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 481ms May 03 20:20:12 volumioopiz volumio[916]: info: sendMpdCommand add "NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac" took 465 milliseconds May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 34ms May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 30ms May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand play May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:12 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces system playlist update May 03 20:20:12 volumioopiz volumio[916]: info: Ignoring MPD Status Update May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 169ms May 03 20:20:12 volumioopiz volumio[916]: info: sendMpdCommand play took 159 milliseconds May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 157ms May 03 20:20:12 volumioopiz volumio[916]: info: ------------------------------ 153ms May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:12 volumioopiz volumio[916]: info: sendMpdCommand status took 58 milliseconds May 03 20:20:12 volumioopiz volumio[916]: info: sendMpdCommand status took 52 milliseconds May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:12 volumioopiz volumio[916]: info: May 03 20:20:12 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:12 volumioopiz volumio[916]: info: ControllerMpd::getState May 03 20:20:12 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand status May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand status took 551 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand status took 535 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 527 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 525 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand status took 518 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand status took 511 milliseconds May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseState May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus stop May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:13 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 700ms May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 753ms May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 173 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 156 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 151 milliseconds May 03 20:20:13 volumioopiz volumio[916]: info: sendMpdCommand playlistinfo took 151 milliseconds May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: verbose: ControllerMpd::parseTrackInfo May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:13 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"969 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:13 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"977 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:13 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: ControllerMpd::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::servicePushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"977 Kbps","isStreaming":false,"title":"How Do You Do It","artist":"Alvin Lee","album":"The Last Show","uri":"NAS/DS713/Alvin Lee - 2013 - The Last Show (Rianman RM13022 USA)/03 - How Do You Do It.flac","trackType":"flac"} May 03 20:20:13 volumioopiz volumio[916]: verbose: CURRENT POSITION 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState stateService play May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::syncState currentStatus play May 03 20:20:13 volumioopiz volumio[916]: info: Received an update from plugin. extracting info from payload May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:13 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:13 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 1150ms May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 1113ms May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 1096ms May 03 20:20:13 volumioopiz volumio[916]: info: ------------------------------ 1092ms May 03 20:20:24 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 20:20:24 volumioopiz volumio[916]: info: CURURI: music-library/NAS/DS713/Manfred Mann's Earth Band May 03 20:20:24 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:20:45 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 03 20:20:45 volumioopiz volumio[916]: info: CURURI: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station May 03 20:20:46 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Angel You.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Resurrection.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/'Belle' Of The Earth.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Hollywood Town.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Waiting For The Rain.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Don't Kill It Carol.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Platform End.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Are - I Am.flac May 03 20:20:46 volumioopiz volumio[916]: info: Preloading song: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Angels At My Gate.flac May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Angel You.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Resurrection.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/'Belle' Of The Earth.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Waiting For The Rain.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Hollywood Town.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Don't Kill It Carol.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Platform End.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Are - I Am.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Angels At My Gate.flac in service mpd May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FYou%20Angel%20You.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Angel You.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FResurrection.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Resurrection.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2F'Belle'%20Of%20The%20Earth.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/'Belle' Of The Earth.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FWaiting%20For%20The%20Rain.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Waiting For The Rain.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FHollywood%20Town.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Hollywood Town.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FDon't%20Kill%20It%20Carol.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Don't Kill It Carol.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FPlatform%20End.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Platform End.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FYou%20Are%20-%20I%20Am.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/You Are - I Am.flac May 03 20:20:47 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FAngels%20At%20My%20Gate.flac&metadata=false May 03 20:20:47 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Angels At My Gate.flac May 03 20:20:53 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:20:53 volumioopiz volumio[916]: info: CoreCommandRouter::volumioReplaceandPlayItems May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::ClearQueue May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::stop May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::setConsumeUpdateService undefined May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::stPlaybackTimer May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::updateTrackBlock May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::getTrackBlock May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::pushState May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:53 volumioopiz volumio[916]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 20:20:53 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushState May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::serviceStop May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::getTrack 2 May 03 20:20:53 volumioopiz volumio[916]: info: CoreCommandRouter::serviceStop May 03 20:20:53 volumioopiz volumio[916]: info: ControllerMpd::stop May 03 20:20:53 volumioopiz volumio[916]: verbose: ControllerMpd::sendMpdCommand stop May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::clearPlayQueue May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::saveQueue May 03 20:20:53 volumioopiz volumio[916]: info: CoreCommandRouter::volumioPushQueue May 03 20:20:53 volumioopiz volumio[916]: info: CoreStateMachine::addQueueItems May 03 20:20:53 volumioopiz volumio[916]: info: CorePlayQueue::addQueueItems May 03 20:20:53 volumioopiz volumio[916]: info: Preload queue cleared May 03 20:20:53 volumioopiz volumio[916]: info: Adding Item to queue: music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station May 03 20:20:53 volumioopiz volumio[916]: info: Exploding uri music-library/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station in service mpd May 03 20:20:53 volumioopiz volumio[916]: info: May 03 20:20:53 volumioopiz volumio[916]: ---------------------------- MPD announces state update: player May 03 20:20:53 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2F'Belle'%20Of%20The%20Earth.flac&metadata=false May 03 20:20:53 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/'Belle' Of The Earth.flac May 03 20:20:53 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FAngel%20Station%20ISRC.cue&metadata=false May 03 20:20:53 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Angel Station ISRC.cue May 03 20:20:53 volumioopiz volumio[916]: info: ALBUMART /albumart?cacheid=258&web=Manfred%20Mann's%20Earth%20Band/Angel%20Station/extralarge&path=%2Fmnt%2FNAS%2FDS713%2FManfred%20Mann's%20Earth%20Band%2F1979%20Angel%20Station%2FAngel%20Station%20ISRC.cue&metadata=false May 03 20:20:53 volumioopiz volumio[916]: info: URI /mnt/NAS/DS713/Manfred Mann's Earth Band/1979 Angel Station/Angel Station ISRC.cue May 03 20:20:53 volumioopiz volumio[916]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 20:20:53 volumioopiz volumio[916]: Error: Unable to resolve or reject the same promise twice May 03 20:20:53 volumioopiz volumio[916]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 03 20:20:53 volumioopiz volumio[916]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 May 03 20:20:53 volumioopiz volumio[916]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 03 20:20:53 volumioopiz volumio[916]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 03 20:20:53 volumioopiz volumio[916]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 03 20:20:53 volumioopiz volumio[916]: at Socket.emit (events.js:400:28) May 03 20:20:53 volumioopiz volumio[916]: at addChunk (internal/streams/readable.js:293:12) May 03 20:20:53 volumioopiz volumio[916]: at readableAddChunk (internal/streams/readable.js:263:11) May 03 20:20:53 volumioopiz volumio[916]: at Socket.Readable.push (internal/streams/readable.js:206:10) May 03 20:20:53 volumioopiz volumio[916]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 03 20:20:53 volumioopiz volumio[916]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 20:20:57 volumioopiz sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 20:19 May 03 20:20:57 volumioopiz sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 24 Feb 2024 08:04:14 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="0bb66bd364a7c58bdecf77539594b49c"