-- 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"