-- Logs begin at Mon 2025-05-12 21:35:18 CEST, end at Mon 2025-05-12 22:02:31 CEST. -- May 12 22:01:36 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:01:36 volumio volumio[946]: info: CURURI: music-library/INTERNAL May 12 22:01:36 volumio volumio[946]: info: Preload queue cleared May 12 22:01:44 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:01:44 volumio volumio[946]: info: CURURI: music-library/INTERNAL/Astropilot - Fruits of the Imagination (2007) May 12 22:01:44 volumio volumio[946]: info: Preload queue cleared May 12 22:01:44 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac May 12 22:01:44 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac in service mpd May 12 22:01:44 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Astropilot/Fruits%20of%20the%20Imagination/extralarge&path=%2Fmnt%2FINTERNAL%2FAstropilot%20-%20Fruits%20of%20the%20Imagination%20(2007)%2FAstropilot%20-%20Fruits%20of%20the%20Imagination.flac&metadata=false May 12 22:01:44 volumio volumio[946]: info: URI /mnt/INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac May 12 22:01:45 volumio volumio[946]: info: Preload queue cleared May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::stop May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:01:45 volumio volumio[946]: info: Preload queue cleared May 12 22:01:45 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac May 12 22:01:45 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::play index 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand stop took 33 milliseconds May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand stop took 8 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 2 milliseconds May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand clear took 6 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand playlistinfo took 6 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 3 milliseconds May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac" May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: No code May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: No code May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 39ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 95ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 95ms May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: error: updateQueue error: null May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 102ms May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand add "INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac" took 100 milliseconds May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 9ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 7ms May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:45 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 27ms May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand play took 24 milliseconds May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 25ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 24ms May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 18 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 18 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 16 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 14 milliseconds May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: May 12 22:01:45 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand playlistinfo took 27 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand playlistinfo took 27 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand playlistinfo took 26 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand playlistinfo took 26 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 23 milliseconds May 12 22:01:45 volumio volumio[946]: info: sendMpdCommand status took 22 milliseconds May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:45 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:45 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:45 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:45 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:45 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:45 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:45 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 95ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 163ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 159ms May 12 22:01:45 volumio volumio[946]: info: ------------------------------ 157ms May 12 22:01:46 volumio volumio[946]: info: sendMpdCommand playlistinfo took 117 milliseconds May 12 22:01:46 volumio volumio[946]: info: sendMpdCommand playlistinfo took 117 milliseconds May 12 22:01:46 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:46 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:46 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:46 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:46 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:46 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":4360,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"491 Kbps","isStreaming":false,"title":"Sansara","artist":"Astropilot","album":"Fruits of the Imagination","uri":"INTERNAL/Astropilot - Fruits of the Imagination (2007)/Astropilot - Fruits of the Imagination.flac","trackType":"flac"} May 12 22:01:46 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:46 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:46 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:46 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:46 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:46 volumio volumio[946]: info: ------------------------------ 209ms May 12 22:01:46 volumio volumio[946]: info: ------------------------------ 207ms May 12 22:01:56 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:01:56 volumio volumio[946]: info: CURURI: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991) May 12 22:01:56 volumio volumio[946]: info: Preload queue cleared May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:01:56 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F01%20-%20Escher%20Drawings%20Part%201.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F02%20-%20Escher%20Drawings%20Part%202.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F03%20-%20Escher%20Drawings%20Part%203.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F04%20-%20Escher%20Drawings%20Part%204.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F05%20-%20Escher%20Drawings%20Part%205.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F06%20-%20Escher%20Drawings%20Part%206.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:01:56 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 in service mpd May 12 22:01:56 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Bas%20Broekhuis/The%20Escher%20Drawings/extralarge&path=%2Fmnt%2FINTERNAL%2FBas%20Broekhuis%20-%20The%20Escher%20Drawings%20(New%20Age%20Music%20-%201991)%2F07%20-%20Escher%20Drawings%20Part%207.mp3&metadata=false May 12 22:01:56 volumio volumio[946]: info: URI /mnt/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:01:57 volumio volumio[946]: info: Preload queue cleared May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::stop May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:01:57 volumio volumio[946]: info: Preload queue cleared May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::play index 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:01:57 volumio volumio[946]: info: Preload queue cleared May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:01:57 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:01:57 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand stop took 46 milliseconds May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand stop took 6 milliseconds May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand clear took 8 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3" May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: No code May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: No code May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 110ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 109ms May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: error: updateQueue error: null May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 117ms May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 115 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand add "INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3" took 115 milliseconds May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 12ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 10ms May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play May 12 22:01:57 volumio volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 129ms May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:01:57 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 26ms May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand play took 24 milliseconds May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 22ms May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 21ms May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 15 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 16 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 13 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 13 milliseconds May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: May 12 22:01:57 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::getState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 15 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 16 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 16 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 16 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 13 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand status took 12 milliseconds May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:57 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:57 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:57 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 72ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 137ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 135ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 134ms May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 106 milliseconds May 12 22:01:57 volumio volumio[946]: info: sendMpdCommand playlistinfo took 106 milliseconds May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:57 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":599,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Escher Drawings Part 1","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3","trackType":"mp3"} May 12 22:01:57 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:01:57 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:01:57 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:01:57 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 179ms May 12 22:01:57 volumio volumio[946]: info: ------------------------------ 178ms May 12 22:02:05 volumio volumio[946]: info: Preload queue cleared May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::stop May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:05 volumio volumio[946]: info: Preload queue cleared May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/01 - Escher Drawings Part 1.mp3 May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::play index 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:05 volumio volumio[946]: info: Preload queue cleared May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/03 - Escher Drawings Part 3.mp3 May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/04 - Escher Drawings Part 4.mp3 May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/05 - Escher Drawings Part 5.mp3 May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/06 - Escher Drawings Part 6.mp3 May 12 22:02:05 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:02:05 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/07 - Escher Drawings Part 7.mp3 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3 May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand stop took 48 milliseconds May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand stop took 7 milliseconds May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand clear took 7 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 4 milliseconds May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3" May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: No code May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: No code May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 88ms May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 87ms May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: error: updateQueue error: null May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 92ms May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand playlistinfo took 90 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand add "INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3" took 89 milliseconds May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 8ms May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 6ms May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play May 12 22:02:05 volumio volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 104ms May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:05 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 22ms May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand play took 19 milliseconds May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 17ms May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 18ms May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 11 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 10 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: May 12 22:02:05 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds May 12 22:02:05 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:05 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:05 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:05 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:05 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:05 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:05 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:05 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:05 volumio volumio[946]: info: ------------------------------ 58ms May 12 22:02:06 volumio volumio[946]: info: ------------------------------ 119ms May 12 22:02:06 volumio volumio[946]: info: ------------------------------ 116ms May 12 22:02:06 volumio volumio[946]: info: ------------------------------ 115ms May 12 22:02:06 volumio volumio[946]: info: sendMpdCommand playlistinfo took 93 milliseconds May 12 22:02:06 volumio volumio[946]: info: sendMpdCommand playlistinfo took 93 milliseconds May 12 22:02:06 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:06 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:06 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:06 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:06 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:06 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":736,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Escher Drawings Part 2","artist":"Bas Broekhuis","album":"The Escher Drawings","uri":"INTERNAL/Bas Broekhuis - The Escher Drawings (New Age Music - 1991)/02 - Escher Drawings Part 2.mp3","trackType":"mp3"} May 12 22:02:06 volumio volumio[946]: verbose: CURRENT POSITION 1 May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:06 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:06 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:06 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:06 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:06 volumio volumio[946]: info: ------------------------------ 165ms May 12 22:02:06 volumio volumio[946]: info: ------------------------------ 164ms May 12 22:02:12 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:02:12 volumio volumio[946]: info: CURURI: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler May 12 22:02:12 volumio volumio[946]: info: Preload queue cleared May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:12 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F01%20Battle%20Los%20Angeles%20Hymn.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F02%20Battle%20Los%20Angeles%20Main%20Titles.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F03%20Arrival.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F04%20Marines%20Don_t%20Quit.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F05%20Command%20And%20Control%20Center.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F06%20Elegy.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F07%20Redemption.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F08%20For%20Home%2C%20Country%2C%20and%20Family.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 in service mpd May 12 22:02:12 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F09%20War%20Hymn.mp3&metadata=false May 12 22:02:12 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:12 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F10%20Evac.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F11%20To%20Hell%20And%20Back.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F12%20Mobilized.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F13%20The%20Freeway.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F14%20The%20Drone.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F15%20Casualty%20of%20War.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F16%20Rebalance.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F17%20Regret.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F18%20Shelf%20Life.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F19%20The%20World%20Is%20At%20War.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F20%20Abandoning%20Los%20Angeles.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F21%20Battle%20Los%20Angeles.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 in service mpd May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Brian%20Tyler/Battle%3A%20Los%20Angeles/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2F22%20We%20Are%20Still%20Here.mp3&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:13 volumio volumio[946]: info: Preload queue cleared May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 1 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::stop May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:13 volumio volumio[946]: info: Preload queue cleared May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::play index 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:13 volumio volumio[946]: info: Preload queue cleared May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:13 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:13 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC] May 12 22:02:13 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC] in service mpd May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F01.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Canyon%20Voices.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/01. Deborah Martin, Greg Klamt & Mark Rownd - Canyon Voices.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F02.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Dreamwalking.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/02. Deborah Martin, Greg Klamt & Mark Rownd - Dreamwalking.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F03.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Shimmers.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/03. Deborah Martin, Greg Klamt & Mark Rownd - Shimmers.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F04.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Blue%20Lake.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/04. Deborah Martin, Greg Klamt & Mark Rownd - Blue Lake.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F05.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Black%20Bamboo.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/05. Deborah Martin, Greg Klamt & Mark Rownd - Black Bamboo.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F06.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Taos.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/06. Deborah Martin, Greg Klamt & Mark Rownd - Taos.flac May 12 22:02:13 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd/Convergence/extralarge&path=%2Fmnt%2FINTERNAL%2FBattle%20'Los%20Angeles'%20Soundtrack%20%5B2011%5D%20-%20Brian%20Tyler%2FDeborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Convergence%20(2002)%5BFLAC%5D%2F07.%20Deborah%20Martin%2C%20Greg%20Klamt%20%26%20Mark%20Rownd%20-%20Tibet.flac&metadata=false May 12 22:02:13 volumio volumio[946]: info: URI /mnt/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC]/07. Deborah Martin, Greg Klamt & Mark Rownd - Tibet.flac May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand stop took 60 milliseconds May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand stop took 12 milliseconds May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 26 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand clear took 25 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 14 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 13 milliseconds May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3" May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: No code May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: No code May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 104ms May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 103ms May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: error: updateQueue error: null May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 112ms May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 107 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand add "INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3" took 106 milliseconds May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 13ms May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 13ms May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play May 12 22:02:13 volumio volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 147ms May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:13 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 11ms May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand play took 8 milliseconds May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 7ms May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 6ms May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 9 milliseconds May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 7 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: May 12 22:02:13 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 23 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 22 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 21 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 22 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 14 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand status took 12 milliseconds May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:13 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:13 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 71ms May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 105ms May 12 22:02:13 volumio volumio[946]: info: ------------------------------ 104ms May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 78 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 77 milliseconds May 12 22:02:13 volumio volumio[946]: info: sendMpdCommand playlistinfo took 77 milliseconds May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:13 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:13 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:13 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:13 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:13 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:13 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:14 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:14 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":153,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Battle Los Angeles Hymn","artist":"Brian Tyler","album":"Battle: Los Angeles","uri":"INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3","trackType":"mp3"} May 12 22:02:14 volumio volumio[946]: verbose: CURRENT POSITION 0 May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:14 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:14 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:14 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:14 volumio volumio[946]: info: ------------------------------ 207ms May 12 22:02:14 volumio volumio[946]: info: ------------------------------ 197ms May 12 22:02:14 volumio volumio[946]: info: ------------------------------ 196ms May 12 22:02:22 volumio volumio[946]: info: Preload queue cleared May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::stop May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:22 volumio volumio[946]: info: Preload queue cleared May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/01 Battle Los Angeles Hymn.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/02 Battle Los Angeles Main Titles.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/03 Arrival.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::play index 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:22 volumio volumio[946]: info: Preload queue cleared May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/05 Command And Control Center.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/06 Elegy.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/07 Redemption.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/08 For Home, Country, and Family.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/09 War Hymn.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/10 Evac.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/11 To Hell And Back.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/12 Mobilized.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/13 The Freeway.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/14 The Drone.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/15 Casualty of War.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/16 Rebalance.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/17 Regret.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/18 Shelf Life.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/19 The World Is At War.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/20 Abandoning Los Angeles.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/21 Battle Los Angeles.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/22 We Are Still Here.mp3 May 12 22:02:22 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC] May 12 22:02:22 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/Deborah Martin, Greg Klamt & Mark Rownd - Convergence (2002)[FLAC] May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3 May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand stop took 56 milliseconds May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand stop took 9 milliseconds May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand clear May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand clear took 7 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 4 milliseconds May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3" May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: No code May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: No code May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 89ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 88ms May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: error: updateQueue error: null May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 96ms May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 94 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand add "INTERNAL/Battle 'Los Angeles' Soundtrack [2011] - Brian Tyler/04 Marines Don_t Quit.mp3" took 95 milliseconds May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 11ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 9ms May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand play May 12 22:02:22 volumio volumio[946]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 107ms May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces system playlist update May 12 22:02:22 volumio volumio[946]: info: Ignoring MPD Status Update May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 25ms May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand play took 22 milliseconds May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 21ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 20ms May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 11 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 11 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 9 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 8 milliseconds May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: May 12 22:02:22 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::getState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand status May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 9 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 8 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 6 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand status took 5 milliseconds May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseState May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [413B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus stop May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [413B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:22 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [413B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:22 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [413B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:22 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 71ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 156ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 154ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 153ms May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 132 milliseconds May 12 22:02:22 volumio volumio[946]: info: sendMpdCommand playlistinfo took 131 milliseconds May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: verbose: ControllerMpd::parseTrackInfo May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [413B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:22 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ControllerMpd::pushState May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::servicePushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: [420B blob data] May 12 22:02:22 volumio volumio[946]: verbose: CURRENT POSITION 3 May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState stateService play May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::syncState currentStatus play May 12 22:02:22 volumio volumio[946]: info: Received an update from plugin. extracting info from payload May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:22 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:22 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 207ms May 12 22:02:22 volumio volumio[946]: info: ------------------------------ 206ms May 12 22:02:27 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:02:27 volumio volumio[946]: info: CURURI: music-library/INTERNAL/bck scatolik-nuovo audio May 12 22:02:27 volumio volumio[946]: info: Preload queue cleared May 12 22:02:28 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 12 22:02:29 volumio volumio[946]: info: CURURI: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck May 12 22:02:29 volumio volumio[946]: info: Preload queue cleared May 12 22:02:29 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape May 12 22:02:29 volumio volumio[946]: info: Preloading song: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac May 12 22:02:29 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape in service mpd May 12 22:02:29 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FZPreisner10Pieces4Piano.ape&metadata=false May 12 22:02:29 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape May 12 22:02:29 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac in service mpd May 12 22:02:29 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Symatic%20Star/Transmissions/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FSymatic_Star_Transmissions.flac&metadata=false May 12 22:02:29 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac May 12 22:02:30 volumio volumio[946]: info: Preload queue cleared May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::volumioReplaceandPlayItems May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::ClearQueue May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::stPlaybackTimer May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::pushState May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::volumioPushState May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::serviceStop May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrack 3 May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::serviceStop May 12 22:02:30 volumio volumio[946]: info: ControllerMpd::stop May 12 22:02:30 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::clearPlayQueue May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:30 volumio volumio[946]: info: Preload queue cleared May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape May 12 22:02:30 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::volumioPushQueue May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::saveQueue May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::updateTrackBlock May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrackBlock May 12 22:02:30 volumio volumio[946]: info: CoreCommandRouter::volumioPlay May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::play index 0 May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::addQueueItems May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::addQueueItems May 12 22:02:30 volumio volumio[946]: info: Preload queue cleared May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac May 12 22:02:30 volumio volumio[946]: info: Using cached record of: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Symatic_Star_Transmissions.flac May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE] May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE] in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[Ecm NS 1798] MORTON FELDMAN (2008) The Viola in My Life (by.Barabbovich).zip May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[Ecm NS 1798] MORTON FELDMAN (2008) The Viola in My Life (by.Barabbovich).zip in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/AAVV feat Rino Zurzolo, Antonio Onorato, Riccardo Zappa (1995) O' cuorp 'e Napule (by.Barabbovich).zip May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/AAVV feat Rino Zurzolo, Antonio Onorato, Riccardo Zappa (1995) O' cuorp 'e Napule (by.Barabbovich).zip in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Kevin Kendle - Tarot - The Mind Body And Soul Series.zip May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Kevin Kendle - Tarot - The Mind Body And Soul Series.zip in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Michael Nyman And Motion Trio - Acoustic Accordions May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Michael Nyman And Motion Trio - Acoustic Accordions in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Radiodervish - 1998 - Lingua Contro Lingua.zip May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Radiodervish - 1998 - Lingua Contro Lingua.zip in service mpd May 12 22:02:30 volumio volumio[946]: info: Adding Item to queue: music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/VA - UKF Bass Culture May 12 22:02:30 volumio volumio[946]: info: Exploding uri music-library/INTERNAL/bck scatolik-nuovo audio/scatolic bck/VA - UKF Bass Culture in service mpd May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::stop May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::play index undefined May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::setConsumeUpdateService undefined May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:30 volumio volumio[946]: info: CoreStateMachine::startPlaybackTimer May 12 22:02:30 volumio volumio[946]: info: CorePlayQueue::getTrack 0 May 12 22:02:30 volumio volumio[946]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/bck scatolik-nuovo audio/scatolic bck/ZPreisner10Pieces4Piano.ape May 12 22:02:30 volumio volumio[946]: verbose: ControllerMpd::sendMpdCommand stop May 12 22:02:30 volumio volumio[946]: info: May 12 22:02:30 volumio volumio[946]: ---------------------------- MPD announces state update: player May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F01.%20A%20Promise%20Of%20Love.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/01. A Promise Of Love.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F02.%20Together%20As%20One.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/02. Together As One.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F03.%20Two%20Flames.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/03. Two Flames.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F04.%20My%20Valentine.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/04. My Valentine.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F05.%20The%20Yearning.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/05. The Yearning.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F06.%20All%20Of%20My%20Heart.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/06. All Of My Heart.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F07.%20Love%20Is%20All%20We%20Need.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/07. Love Is All We Need.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Midori/A%20Promise%20of%20Love/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F(New%20Age)%20Midori%20-%20A%20Promise%20Of%20Love%20%5B2012%5D%20(Mp3)-Gonnd.Com%2F08.%20Cupid's%20Arrow.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/(New Age) Midori - A Promise Of Love [2012] (Mp3)-Gonnd.Com/08. Cupid's Arrow.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=//extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2F%5B1970%5D%20-%2005%20Atom%20Heart%20Mother%20(Remastered%201994)%5BAPE%5D%2FAtom%20Heart%20Mother%20(CDImage).ape&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/[1970] - 05 Atom Heart Mother (Remastered 1994)[APE]/Atom Heart Mother (CDImage).ape May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F01%20-%203_4%20e%20un%20po'.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/01 - 3_4 e un po'.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F02%20-%20Luandando.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/02 - Luandando.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F03%20-%20Neapolitan%20Minor%20Blues.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/03 - Neapolitan Minor Blues.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F04%20-%20Palummella.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/04 - Palummella.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F05%20-%20Tammurriata%20Nera.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/05 - Tammurriata Nera.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F06%20-%20Morte%20di%20un%20fiore.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/06 - Morte di un fiore.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F07%20-%20Napulegna.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/07 - Napulegna.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F08%20-%20Manha%20de%20Carnaval.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/08 - Manha de Carnaval.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%2F09%20-%20Assaje.mp3&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato/09 - Assaje.mp3 May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%20-%20Jazz%20Napoletano%20(2004).zip&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip May 12 22:02:30 volumio volumio[946]: info: ALBUMART /albumart?cacheid=427&web=Antonio%20Onorato/Jazz%20Napoletano/extralarge&path=%2Fmnt%2FINTERNAL%2Fbck%20scatolik-nuovo%20audio%2Fscatolic%20bck%2FAntonio%20Onorato%20-%20Jazz%20Napoletano%20(2004).zip&metadata=false May 12 22:02:30 volumio volumio[946]: info: URI /mnt/INTERNAL/bck scatolik-nuovo audio/scatolic bck/Antonio Onorato - Jazz Napoletano (2004).zip May 12 22:02:30 volumio volumio[946]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:02:30 volumio volumio[946]: Error: Unable to resolve or reject the same promise twice May 12 22:02:30 volumio volumio[946]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 12 22:02:30 volumio volumio[946]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 May 12 22:02:30 volumio volumio[946]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 12 22:02:30 volumio volumio[946]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 12 22:02:30 volumio volumio[946]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 12 22:02:30 volumio volumio[946]: at Socket.emit (events.js:400:28) May 12 22:02:30 volumio volumio[946]: at addChunk (internal/streams/readable.js:293:12) May 12 22:02:30 volumio volumio[946]: at readableAddChunk (internal/streams/readable.js:263:11) May 12 22:02:30 volumio volumio[946]: at Socket.Readable.push (internal/streams/readable.js:206:10) May 12 22:02:30 volumio volumio[946]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) May 12 22:02:30 volumio volumio[946]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 22:02:31 volumio sudo[5159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-12 22:01 May 12 22:02:31 volumio sudo[5159]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 12:21:23 PM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a9588e47e4b05f1a05bf70b4acc7555c"