-- Logs begin at Wed 2024-04-03 12:31:09 EEST, end at Wed 2024-04-03 12:36:37 EEST. -- Apr 03 12:35:00 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 12:35:00 volumio volumio[869]: info: CURURI: music-library/USB/Volume Apr 03 12:35:02 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:04 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 12:35:04 volumio volumio[869]: info: CURURI: music-library/USB/Volume/192.0 Apr 03 12:35:05 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:11 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::ClearQueue Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::stPlaybackTimer Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::serviceStop Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::serviceStop Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::stop Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::clearPlayQueue Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::saveQueue Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushQueue Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::addQueueItems Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::addQueueItems Apr 03 12:35:11 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:11 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV Apr 03 12:35:11 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV in service mpd Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand stop took 456 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F01.%20Judith%20Nijland%20-%20It's%20Raining%20Men.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F02.%20Judith%20Nijland%20-%20I%20Wonder%20Why.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F03.%20Judith%20Nijland%20-%20Here%20Comes%20The%20Rain%20Again.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/03. Judith Nijland - Here Comes The Rain Again.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F04.%20Judith%20Nijland%20-%20Why%20Can't%20This%20Be%20Love.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/04. Judith Nijland - Why Can't This Be Love.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F05.%20Judith%20Nijland%20-%20Early%20Morning.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/05. Judith Nijland - Early Morning.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F06.%20Judith%20Nijland%20-%20Whisper%20In%20My%20Ear.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/06. Judith Nijland - Whisper In My Ear.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F07.%20Judith%20Nijland%20-%20The%20beautiful%20reality%20of%20life.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/07. Judith Nijland - The beautiful reality of life.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F08.%20Judith%20Nijland%20-%20Close%20To%20You.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/08. Judith Nijland - Close To You.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F09.%20Judith%20Nijland%20-%20After.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/09. Judith Nijland - After.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F10.%20Judith%20Nijland%20-%20Wrong%20Time%2C%20Wrong%20Place.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/10. Judith Nijland - Wrong Time, Wrong Place.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F11.%20Judith%20Nijland%20-%20Slow%20Suicide.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/11. Judith Nijland - Slow Suicide.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F12.%20Judith%20Nijland%20-%20Unconditional%20Love.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/12. Judith Nijland - Unconditional Love.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F13.%20Judith%20Nijland%20-%20Hey%20Girl.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/13. Judith Nijland - Hey Girl.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F14.%20Judith%20Nijland%20-%20As%20The%20World%20Turns.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/14. Judith Nijland - As The World Turns.ape Apr 03 12:35:11 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Judith%20Nijland/Marantz%20Acoustic%20Solution%20Vol.4/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2F2008%20-%20Judith%20Nijland%20-%20Marantz%20Acoustic%20Solutions%20IV%2F15.%20Judith%20Nijland%20-%20Little%20Boy.ape&metadata=false Apr 03 12:35:11 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/15. Judith Nijland - Little Boy.ape Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushQueue Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::saveQueue Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPlay Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::play index 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::play index undefined Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::startPlaybackTimer Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand status took 28 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand status took 27 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand status took 26 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand stop took 1 milliseconds Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand clear took 3 milliseconds Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape" Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Акустична песен","artist":"Acoustic Trio 3000","album":"Yo Ho","uri":"USB/Нов_том/Acoustic Trio 3000 -YoHo/01.mp3","trackType":"mp3"} Apr 03 12:35:11 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: No code Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Акустична песен","artist":"Acoustic Trio 3000","album":"Yo Ho","uri":"USB/Нов_том/Acoustic Trio 3000 -YoHo/01.mp3","trackType":"mp3"} Apr 03 12:35:11 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: No code Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Акустична песен","artist":"Acoustic Trio 3000","album":"Yo Ho","uri":"USB/Нов_том/Acoustic Trio 3000 -YoHo/01.mp3","trackType":"mp3"} Apr 03 12:35:11 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: No code Apr 03 12:35:11 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:11 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:11 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 48ms Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 47ms Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: error: updateQueue error: null Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 46ms Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand add "USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape" took 44 milliseconds Apr 03 12:35:11 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand play Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 19ms Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 18ms Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 17ms Apr 03 12:35:11 volumio volumio[869]: info: sendMpdCommand play took 11 milliseconds Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: info: Apr 03 12:35:11 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:11 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 5ms Apr 03 12:35:11 volumio volumio[869]: info: ------------------------------ 4ms Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 442 milliseconds Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 443 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 443 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 447ms Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: Apr 03 12:35:12 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 4 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:12 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:12 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 468ms Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 468ms Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 03 12:35:12 volumio volumio[869]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:12 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:12 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:12 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:12 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:12 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:12 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 53ms Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 49ms Apr 03 12:35:12 volumio volumio[869]: info: ------------------------------ 50ms Apr 03 12:35:18 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Apr 03 12:35:18 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioNext Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::next Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::stPlaybackTimer Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::serviceStop Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::serviceStop Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::stop Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand stop took 108 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::play index undefined Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::startPlaybackTimer Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand stop took 0 milliseconds Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand clear took 1 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand status took 0 milliseconds Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape" Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"It's Raining Men","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/01. Judith Nijland - It's Raining Men.ape","trackType":"ape"} Apr 03 12:35:21 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: No code Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:21 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: No code Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:21 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: No code Apr 03 12:35:21 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:21 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:21 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 39ms Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 37ms Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 37ms Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: error: updateQueue error: null Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 39ms Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand add "USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape" took 39 milliseconds Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand play Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 13ms Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 12ms Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 11ms Apr 03 12:35:21 volumio volumio[869]: info: sendMpdCommand play took 10 milliseconds Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 10ms Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:21 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:21 volumio volumio[869]: info: ------------------------------ 0ms Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:21 volumio volumio[869]: info: Apr 03 12:35:21 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:21 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:21 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand status took 401 milliseconds Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: info: Apr 03 12:35:22 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand status took 401 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand status took 401 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 407ms Apr 03 12:35:22 volumio volumio[869]: info: Apr 03 12:35:22 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:22 volumio volumio[869]: info: Apr 03 12:35:22 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand status took 8 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:22 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:22 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 438ms Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 437ms Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 03 12:35:22 volumio volumio[869]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 03 12:35:22 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:22 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:22 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":178,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Wonder Why","artist":"Judith Nijland","album":"Marantz Acoustic Solution Vol.4","uri":"USB/Volume/192.0/2008 - Judith Nijland - Marantz Acoustic Solutions IV/02. Judith Nijland - I Wonder Why.ape","trackType":"ape"} Apr 03 12:35:22 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:22 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:22 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:22 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 68ms Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 62ms Apr 03 12:35:22 volumio volumio[869]: info: ------------------------------ 62ms Apr 03 12:35:29 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 12:35:29 volumio volumio[869]: info: CURURI: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192] Apr 03 12:35:29 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/03 - My Reverie.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/04 - Stella By Starlight.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/05 - 'Round Midnight.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/06 - Jersey Bounce.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/07 - Signing Off.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/08 - Cry Me A River.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/09 - This Year's Kisses.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/10 - Good Morning Heartache.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/11 - (I Was) Born To Be Blue.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/12 - Clap Hands Here Comes Charlie!.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/13 - Spring Can Really Hang You Up The Most.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/14 - Music Goes Round And Around.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/15 - The One I Love Belongs To Somebody Else.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/16 - I Got A Guy.flac Apr 03 12:35:29 volumio volumio[869]: info: Preloading song: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/17 - This Could Be The Start Of Something Big.flac Apr 03 12:35:29 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac in service mpd Apr 03 12:35:29 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F01%20-%20A%20Night%20In%20Tunisia.flac&metadata=false Apr 03 12:35:29 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F02%20-%20You're%20My%20Thril.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/03 - My Reverie.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F03%20-%20My%20Reverie.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/03 - My Reverie.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/04 - Stella By Starlight.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/05 - 'Round Midnight.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F04%20-%20Stella%20By%20Starlight.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/04 - Stella By Starlight.flac Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F05%20-%20'Round%20Midnight.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/05 - 'Round Midnight.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/06 - Jersey Bounce.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F06%20-%20Jersey%20Bounce.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/06 - Jersey Bounce.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/07 - Signing Off.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F07%20-%20Signing%20Off.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/07 - Signing Off.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/08 - Cry Me A River.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F08%20-%20Cry%20Me%20A%20River.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/08 - Cry Me A River.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/09 - This Year's Kisses.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F09%20-%20This%20Year's%20Kisses.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/09 - This Year's Kisses.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/10 - Good Morning Heartache.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F10%20-%20Good%20Morning%20Heartache.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/10 - Good Morning Heartache.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/11 - (I Was) Born To Be Blue.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F11%20-%20(I%20Was)%20Born%20To%20Be%20Blue.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/11 - (I Was) Born To Be Blue.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/12 - Clap Hands Here Comes Charlie!.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F12%20-%20Clap%20Hands%20Here%20Comes%20Charlie!.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/12 - Clap Hands Here Comes Charlie!.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/13 - Spring Can Really Hang You Up The Most.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F13%20-%20Spring%20Can%20Really%20Hang%20You%20Up%20The%20Most.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/13 - Spring Can Really Hang You Up The Most.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/14 - Music Goes Round And Around.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F14%20-%20Music%20Goes%20Round%20And%20Around.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/14 - Music Goes Round And Around.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/15 - The One I Love Belongs To Somebody Else.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F15%20-%20The%20One%20I%20Love%20Belongs%20To%20Somebody%20Else.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/15 - The One I Love Belongs To Somebody Else.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/16 - I Got A Guy.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F16%20-%20I%20Got%20A%20Guy.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/16 - I Got A Guy.flac Apr 03 12:35:30 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/17 - This Could Be The Start Of Something Big.flac in service mpd Apr 03 12:35:30 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D%2F17%20-%20This%20Could%20Be%20The%20Start%20Of%20Something%20Big.flac&metadata=false Apr 03 12:35:30 volumio volumio[869]: info: URI /mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/17 - This Could Be The Start Of Something Big.flac Apr 03 12:35:32 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::ClearQueue Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::stPlaybackTimer Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::serviceStop Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::serviceStop Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::stop Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::clearPlayQueue Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::saveQueue Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushQueue Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::addQueueItems Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::addQueueItems Apr 03 12:35:32 volumio volumio[869]: info: Preload queue cleared Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/03 - My Reverie.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/03 - My Reverie.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/04 - Stella By Starlight.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/04 - Stella By Starlight.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/05 - 'Round Midnight.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/05 - 'Round Midnight.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/06 - Jersey Bounce.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/06 - Jersey Bounce.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/07 - Signing Off.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/07 - Signing Off.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/08 - Cry Me A River.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/08 - Cry Me A River.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/09 - This Year's Kisses.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/09 - This Year's Kisses.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/10 - Good Morning Heartache.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/10 - Good Morning Heartache.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/11 - (I Was) Born To Be Blue.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/11 - (I Was) Born To Be Blue.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/12 - Clap Hands Here Comes Charlie!.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/12 - Clap Hands Here Comes Charlie!.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/13 - Spring Can Really Hang You Up The Most.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/13 - Spring Can Really Hang You Up The Most.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/14 - Music Goes Round And Around.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/14 - Music Goes Round And Around.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/15 - The One I Love Belongs To Somebody Else.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/15 - The One I Love Belongs To Somebody Else.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/16 - I Got A Guy.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/16 - I Got A Guy.flac Apr 03 12:35:32 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/17 - This Could Be The Start Of Something Big.flac Apr 03 12:35:32 volumio volumio[869]: info: Using cached record of: music-library/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/17 - This Could Be The Start Of Something Big.flac Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushQueue Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::saveQueue Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPlay Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::play index 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::play index undefined Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::startPlaybackTimer Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand stop took 116 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand stop took 107 milliseconds Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand clear took 2 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac" Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:32 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: No code Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:32 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: No code Apr 03 12:35:32 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:32 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:32 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 22ms Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 22ms Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: error: updateQueue error: null Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 24ms Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand add "USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac" took 24 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 3ms Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 2ms Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:32 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand play Apr 03 12:35:32 volumio volumio[869]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 28ms Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: info: Apr 03 12:35:32 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:32 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 13ms Apr 03 12:35:32 volumio volumio[869]: info: sendMpdCommand play took 12 milliseconds Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 11ms Apr 03 12:35:32 volumio volumio[869]: info: ------------------------------ 11ms Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 236 milliseconds Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 237 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 237 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4986 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 240ms Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: Apr 03 12:35:33 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 4 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4975 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:33 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4972 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:33 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 260ms Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 261ms Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 03 12:35:33 volumio volumio[869]: info: sendMpdCommand playlistinfo took 20 milliseconds Apr 03 12:35:33 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":891,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4979 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:33 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":996,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5001 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:33 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":996,"duration":248,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5153 Kbps","isStreaming":false,"title":"A Night In Tunisia","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/01 - A Night In Tunisia.flac","trackType":"flac"} Apr 03 12:35:33 volumio volumio[869]: verbose: CURRENT POSITION 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:33 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:33 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:33 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 61ms Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 58ms Apr 03 12:35:33 volumio volumio[869]: info: ------------------------------ 57ms Apr 03 12:35:44 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Apr 03 12:35:44 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioNext Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::next Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::stPlaybackTimer Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::serviceStop Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 0 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::serviceStop Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::stop Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand stop took 108 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::play index undefined Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::startPlaybackTimer Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::clearAddPlayTracks USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand stop took 1 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand clear Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 3 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand clear took 3 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand add "USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac" Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: No code Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: No code Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 28ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 27ms Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: error: updateQueue error: null Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 28ms Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand add "USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac" took 28 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand play Apr 03 12:35:47 volumio volumio[869]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 33ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 14ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 15ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 14ms Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand play took 12 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces system playlist update Apr 03 12:35:47 volumio volumio[869]: info: Ignoring MPD Status Update Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 1ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 1ms Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 402 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: Apr 03 12:35:47 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 404 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 404 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus stop Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 407ms Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 3 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand status took 3 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":94,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4860 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:47 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":622,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4864 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:47 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4864 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:47 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 430ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 430ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 29ms Apr 03 12:35:47 volumio volumio[869]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 03 12:35:47 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":685,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4864 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:47 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":784,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"4847 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:47 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:47 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:47 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:47 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 49ms Apr 03 12:35:47 volumio volumio[869]: info: ------------------------------ 48ms Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioSeek Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::seek Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: TRACKBLOCK {"uri":"mnt/USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","service":"mpd","name":"2 - You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=394&web=Ella%20Fitzgerald/Clap%20Hands%20Here%20Comes%20Charlie!/extralarge&path=%2FUSB%2FVolume%2F192.0%2FElla%20Fitzgerald%20-%20Clap%20Hands%2C%20Here%20Comes%20Charlie!%20(1989)%20%5B24-192%5D&metadata=false","duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","trackType":"flac","channels":2} Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::startPlaybackTimer Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::seek Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: error: null Apr 03 12:35:50 volumio volumio[869]: info: Apr 03 12:35:50 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:50 volumio volumio[869]: info: Apr 03 12:35:50 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:50 volumio volumio[869]: info: Apr 03 12:35:50 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::getState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand status Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand status took 1 milliseconds Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand status took 2 milliseconds Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseState Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34000,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5253 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:50 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:50 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34000,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5253 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:50 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:50 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: ------------------------------ 18ms Apr 03 12:35:50 volumio volumio[869]: info: ------------------------------ 18ms Apr 03 12:35:50 volumio volumio[869]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 03 12:35:50 volumio volumio[869]: verbose: ControllerMpd::parseTrackInfo Apr 03 12:35:50 volumio volumio[869]: info: ControllerMpd::pushState Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::servicePushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34000,"duration":218,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"5253 Kbps","isStreaming":false,"title":"You're My Thril","artist":"Ella Fitzgerald","album":"Clap Hands Here Comes Charlie!","uri":"USB/Volume/192.0/Ella Fitzgerald - Clap Hands, Here Comes Charlie! (1989) [24-192]/02 - You're My Thril.flac","trackType":"flac"} Apr 03 12:35:50 volumio volumio[869]: verbose: CURRENT POSITION 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState stateService play Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::syncState currentStatus play Apr 03 12:35:50 volumio volumio[869]: info: Received an update from plugin. extracting info from payload Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:35:50 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:35:50 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:35:50 volumio volumio[869]: info: ------------------------------ 28ms Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: info: Retrieving Cloud Streaming UI Apr 03 12:35:54 volumio volumio[869]: info: Getting Tidal Cloud Configuration Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: info: Getting Qobuz Cloud Configuration Apr 03 12:35:54 volumio volumio[869]: info: Asking plugin for UI Config Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: info: Getting Spotify Cloud Configuration Apr 03 12:35:54 volumio volumio[869]: info: Asking plugin for UI Config Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: info: Saving Spotify Acccount Apr 03 12:35:54 volumio volumio[869]: info: Got Tidal Cloud Configuration Apr 03 12:35:54 volumio volumio[869]: info: Got it Apr 03 12:35:54 volumio volumio[869]: info: Got it Apr 03 12:35:54 volumio volumio[869]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 03 12:35:54 volumio volumio[869]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::volumioGetBrowseSources Apr 03 12:35:54 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 03 12:35:55 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 03 12:35:57 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Apr 03 12:35:57 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:36:03 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Apr 03 12:36:03 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:36:10 volumio volumio[869]: info: CoreCommandRouter::volumioGetState Apr 03 12:36:10 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:36:19 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 03 12:36:19 volumio volumio[869]: info: CURURI: music-library/USB/Volume/44.100 Apr 03 12:36:19 volumio volumio[869]: info: Preload queue cleared Apr 03 12:36:35 volumio volumio[869]: info: Preload queue cleared Apr 03 12:36:35 volumio volumio[869]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::ClearQueue Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::stop Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::stPlaybackTimer Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::updateTrackBlock Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::getTrackBlock Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::pushState Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:36:35 volumio volumio[869]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 03 12:36:35 volumio volumio[869]: info: CoreCommandRouter::volumioPushState Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::serviceStop Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::getTrack 1 Apr 03 12:36:35 volumio volumio[869]: info: CoreCommandRouter::serviceStop Apr 03 12:36:35 volumio volumio[869]: info: ControllerMpd::stop Apr 03 12:36:35 volumio volumio[869]: verbose: ControllerMpd::sendMpdCommand stop Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::clearPlayQueue Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::saveQueue Apr 03 12:36:35 volumio volumio[869]: info: CoreCommandRouter::volumioPushQueue Apr 03 12:36:35 volumio volumio[869]: info: CoreStateMachine::addQueueItems Apr 03 12:36:35 volumio volumio[869]: info: CorePlayQueue::addQueueItems Apr 03 12:36:35 volumio volumio[869]: info: Preload queue cleared Apr 03 12:36:35 volumio volumio[869]: info: Adding Item to queue: music-library/USB/Volume/44.100/1996 La Bamba Apr 03 12:36:35 volumio volumio[869]: info: Exploding uri music-library/USB/Volume/44.100/1996 La Bamba in service mpd Apr 03 12:36:36 volumio volumio[869]: info: Apr 03 12:36:36 volumio volumio[869]: ---------------------------- MPD announces state update: player Apr 03 12:36:36 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=The%20O-Zone%20Percussion%20Group/La%20Bamba/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F44.100%2F1996%20La%20Bamba%2FThe%20O-Zone%20Percussion%20Group%20-%20La%20Bamba.cue&metadata=false Apr 03 12:36:36 volumio volumio[869]: info: URI /mnt/USB/Volume/44.100/1996 La Bamba/The O-Zone Percussion Group - La Bamba.cue Apr 03 12:36:36 volumio volumio[869]: info: ALBUMART /albumart?cacheid=394&web=The%20O-Zone%20Percussion%20Group/La%20Bamba/extralarge&path=%2Fmnt%2FUSB%2FVolume%2F44.100%2F1996%20La%20Bamba%2FThe%20O-Zone%20Percussion%20Group%20-%20La%20Bamba.cue&metadata=false Apr 03 12:36:36 volumio volumio[869]: info: URI /mnt/USB/Volume/44.100/1996 La Bamba/The O-Zone Percussion Group - La Bamba.cue Apr 03 12:36:36 volumio volumio[869]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 12:36:36 volumio volumio[869]: Error: Unable to resolve or reject the same promise twice Apr 03 12:36:36 volumio volumio[869]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 03 12:36:36 volumio volumio[869]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 03 12:36:36 volumio volumio[869]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 03 12:36:36 volumio volumio[869]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 03 12:36:36 volumio volumio[869]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 03 12:36:36 volumio volumio[869]: at Socket.emit (events.js:400:28) Apr 03 12:36:36 volumio volumio[869]: at addChunk (internal/streams/readable.js:293:12) Apr 03 12:36:36 volumio volumio[869]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 03 12:36:36 volumio volumio[869]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 03 12:36:36 volumio volumio[869]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 03 12:36:36 volumio volumio[869]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 03 12:36:37 volumio sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-03 12:35 Apr 03 12:36:37 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:28:21 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00272c702f983c8787b8de2b7502dec4"