-- Logs begin at Thu 2019-02-14 19:11:59 KST, end at Wed 2025-09-03 08:11:05 KST. -- Sep 03 08:10:03 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::ClearQueue Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::stPlaybackTimer Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::serviceStop Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::serviceStop Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::stop Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::clearPlayQueue Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::addQueueItems Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::addQueueItems Sep 03 08:10:03 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:03 volumio volumio[2939]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215 Sep 03 08:10:03 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215 in service mpd Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F01%20-%20Hebrides%20Overture.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F02%20-%20Violin%20Concerto%20in%20E%20minor%20Allegro%20molto%20appassionato%20-%20Presto.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/02 - Violin Concerto in E minor Allegro molto appassionato - Presto.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F03%20-%20Violin%20Concerto%20in%20E%20minor%20Andante%20-%20Allegretto%20non%20troppo.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/03 - Violin Concerto in E minor Andante - Allegretto non troppo.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F04%20-%20Violin%20Concerto%20in%20E%20minor%20Allegro%20molto%20vivace.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/04 - Violin Concerto in E minor Allegro molto vivace.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F05%20-%20Symphony%20No%203%20in%20A%20minor%20Scottish%20Andante%20con%20moto%20-%20Allegro%20un%20poco%20agitato.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/05 - Symphony No 3 in A minor Scottish Andante con moto - Allegro un poco agitato.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F06%20-%20Symphony%20No%203%20in%20A%20minor%20Scottish%20Vivace%20non%20troppo.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/06 - Symphony No 3 in A minor Scottish Vivace non troppo.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F07%20-%20Symphony%20No%203%20in%20A%20minor%20Scottish%20Adagio.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/07 - Symphony No 3 in A minor Scottish Adagio.flac Sep 03 08:10:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Scottish%20Chamber%20Orchestra%20(SCO)/Mendelssohn%20Violin%20Concerto/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FMendelssohn-Violin%20Concerto-CSO%2CJoseph%20Swensen-Linn-0215%2F08%20-%20Symphony%20No%203%20in%20A%20minor%20Scottish%20Allegro%20vivacissimo%20-%20Allegro%20maestoso%20assai.flac&metadata=false Sep 03 08:10:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/08 - Symphony No 3 in A minor Scottish Allegro vivacissimo - Allegro maestoso assai.flac Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand stop took 103 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPlay Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::play index 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::play index undefined Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::startPlaybackTimer Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::clearAddPlayTracks USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 133 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand stop took 73 milliseconds Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 42 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand clear took 42 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 34 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 24 milliseconds Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand add "USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac" Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:03 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: No code Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:03 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: No code Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:03 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: No code Sep 03 08:10:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 356ms Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 206ms Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 198ms Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:03 volumio volumio[2939]: error: updateQueue error: null Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 217ms Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand add "USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac" took 204 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 39ms Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 36ms Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand play Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:03 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 193ms Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand play took 188 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 185ms Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: ------------------------------ 44ms Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:03 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 30 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 31 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 29 milliseconds Sep 03 08:10:03 volumio volumio[2939]: info: sendMpdCommand status took 28 milliseconds Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:03 volumio volumio[2939]: info: Sep 03 08:10:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:04 volumio volumio[2939]: info: Sep 03 08:10:04 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand status took 74 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand status took 72 milliseconds Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:04 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:04 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":419,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:04 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 209ms Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 266ms Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 262ms Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 263ms Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 194 milliseconds Sep 03 08:10:04 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 195 milliseconds Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:04 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:04 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":602,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:04 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:04 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:04 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:04 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 403ms Sep 03 08:10:04 volumio volumio[2939]: info: ------------------------------ 404ms Sep 03 08:10:04 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:14 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::ClearQueue Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::stPlaybackTimer Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::serviceStop Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::serviceStop Sep 03 08:10:14 volumio volumio[2939]: info: ControllerMpd::stop Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::clearPlayQueue Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::addQueueItems Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::addQueueItems Sep 03 08:10:14 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:14 volumio volumio[2939]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/NAIM_Sampler Sep 03 08:10:14 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/NAIM_Sampler in service mpd Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2001.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2002.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 02.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2003.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 03.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2004.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 04.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2005.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 05.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2006.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 06.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2007.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 07.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2008.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 08.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2009.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 09.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2010.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 10.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2011.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 11.wav Sep 03 08:10:14 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FNAIM_Sampler%2Fnaim_track%2012.wav&metadata=false Sep 03 08:10:14 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 12.wav Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand stop took 174 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPlay Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::play index 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::play index undefined Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::startPlaybackTimer Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::clearAddPlayTracks USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:14 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:14 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand status took 135 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand stop took 102 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand status took 101 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand status took 97 milliseconds Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:14 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 91 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand clear took 98 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 96 milliseconds Sep 03 08:10:14 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 94 milliseconds Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand add "USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav" Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:14 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:14 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hebrides Overture","artist":"Scottish Chamber Orchestra (SCO)","album":"Mendelssohn Violin Concerto","uri":"USB/01DC19762C662800/Classic/Mendelssohn-Violin Concerto-CSO,Joseph Swensen-Linn-0215/01 - Hebrides Overture.flac","trackType":"flac"} Sep 03 08:10:14 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:14 volumio volumio[2939]: info: No code Sep 03 08:10:14 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:14 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:14 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:14 volumio volumio[2939]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 03 08:10:14 volumio volumio[2939]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 03 08:10:14 volumio volumio[2939]: info: ------------------------------ 493ms Sep 03 08:10:14 volumio volumio[2939]: info: ------------------------------ 419ms Sep 03 08:10:14 volumio volumio[2939]: info: ------------------------------ 413ms Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:14 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:14 volumio volumio[2939]: info: Sep 03 08:10:14 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:14 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:14 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:15 volumio volumio[2939]: error: updateQueue error: null Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:15 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 323ms Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand add "USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav" took 272 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 96ms Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 108ms Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand play Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:15 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:15 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:15 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 70ms Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand play took 44 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 44ms Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 42ms Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 493 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 475 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 467 milliseconds Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 23 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 22 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 20 milliseconds Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:15 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:15 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 577ms Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 594ms Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 577ms Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: Sep 03 08:10:15 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 108 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 27 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand status took 22 milliseconds Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:15 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: ------------------------------ 200ms Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 218 milliseconds Sep 03 08:10:15 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 220 milliseconds Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:15 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:15 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:15 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:15 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:15 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":281,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:15 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:16 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:16 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:16 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:16 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:16 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:16 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:16 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:16 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:16 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:16 volumio volumio[2939]: info: ------------------------------ 329ms Sep 03 08:10:16 volumio volumio[2939]: info: ------------------------------ 326ms Sep 03 08:10:16 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:26 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::ClearQueue Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::stPlaybackTimer Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::serviceStop Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::serviceStop Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::stop Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::clearPlayQueue Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::addQueueItems Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::addQueueItems Sep 03 08:10:26 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:26 volumio volumio[2939]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192 Sep 03 08:10:26 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192 in service mpd Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F01%20-%20Rachmaninov%20Piano%20Concerto%20no%202%20-%20Moderato%20Allegro.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F02%20-%20Rachmaninov%20Piano%20Concerto%20no%202%20-%20Adagio%20sostenuto.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/02 - Rachmaninov Piano Concerto no 2 - Adagio sostenuto.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F03%20-%20Rachmaninov%20Piano%20Concerto%20no%202%20-%20Allegro%20scherzando.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/03 - Rachmaninov Piano Concerto no 2 - Allegro scherzando.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F04%20-%20Moments%20Musicaux%20Op%2016%20-%20no%201%20Andantino.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/04 - Moments Musicaux Op 16 - no 1 Andantino.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F05%20-%20Moments%20Musicaux%20Op%2016%20-%20no%202%20Allegretto.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/05 - Moments Musicaux Op 16 - no 2 Allegretto.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F06%20-%20Moments%20Musicaux%20Op%2016%20-%20no%203%20Andante%20cantabile.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/06 - Moments Musicaux Op 16 - no 3 Andante cantabile.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F07%20-%20Moments%20Musicaux%20Op%2016%20-%20no%204%20Presto.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/07 - Moments Musicaux Op 16 - no 4 Presto.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F08%20-%20Moments%20Musicaux%20Op%2016%20-%20no%205%20Adagio%20sostenuto.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/08 - Moments Musicaux Op 16 - no 5 Adagio sostenuto.flac Sep 03 08:10:26 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Dejan%20Lazic/Rachmaninov%20Piano%20concerto%20No.%202/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRachmaninov-Piano%20Concerto%20no.2%2C-Dejan%20Lazic%2CLPO%2CKirill%20Petrenko-Channel-0581%2024192%2F09%20-%20Moments%20Musicaux%20Op%2016%20-%20no%206%20Maestoso.flac&metadata=false Sep 03 08:10:26 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/09 - Moments Musicaux Op 16 - no 6 Maestoso.flac Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand stop took 83 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPlay Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::play index 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::play index undefined Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::startPlaybackTimer Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::clearAddPlayTracks USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 40 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand stop took 6 milliseconds Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 71 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand clear took 71 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 68 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 65 milliseconds Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand add "USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac" Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"naim_track 01.wav","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/NAIM_Sampler/naim_track 01.wav","trackType":"wav"} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: No code Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: No code Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: No code Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 246ms Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 182ms Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 180ms Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio volumio[2939]: error: updateQueue error: null Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 189ms Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand add "USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac" took 170 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 62ms Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 59ms Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand play Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:26 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:26 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 03 08:10:26 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 145ms Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand play took 140 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 139ms Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 136ms Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 48 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 45 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 20 milliseconds Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: Sep 03 08:10:26 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 61 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 60 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 42 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 31 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 20 milliseconds Sep 03 08:10:26 volumio volumio[2939]: info: sendMpdCommand status took 17 milliseconds Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:26 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:26 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:26 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:26 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:26 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:26 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 180ms Sep 03 08:10:26 volumio volumio[2939]: info: ------------------------------ 233ms Sep 03 08:10:27 volumio volumio[2939]: info: ------------------------------ 253ms Sep 03 08:10:27 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 302 milliseconds Sep 03 08:10:27 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 302 milliseconds Sep 03 08:10:27 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 302 milliseconds Sep 03 08:10:27 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:27 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:27 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:27 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:27 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:27 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":422,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6416 Kbps","isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:27 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:27 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":422,"duration":660,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6416 Kbps","isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:27 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:27 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:27 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:27 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:27 volumio volumio[2939]: info: ------------------------------ 525ms Sep 03 08:10:27 volumio volumio[2939]: info: ------------------------------ 480ms Sep 03 08:10:27 volumio volumio[2939]: info: ------------------------------ 478ms Sep 03 08:10:27 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:43 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Sep 03 08:10:43 volumio volumio[2939]: info: CURURI: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160 Sep 03 08:10:43 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:43 volumio volumio[2939]: info: Preloading song: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac Sep 03 08:10:43 volumio volumio[2939]: info: Preloading song: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher02.flac Sep 03 08:10:43 volumio volumio[2939]: info: Preloading song: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher03.flac Sep 03 08:10:43 volumio volumio[2939]: info: Preloading song: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher04.flac Sep 03 08:10:43 volumio volumio[2939]: info: Preloading song: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/scherLeCoq05.flac Sep 03 08:10:43 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac in service mpd Sep 03 08:10:43 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher01.flac&metadata=false Sep 03 08:10:43 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac Sep 03 08:10:43 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher02.flac in service mpd Sep 03 08:10:43 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher02.flac&metadata=false Sep 03 08:10:43 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher02.flac Sep 03 08:10:43 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher03.flac in service mpd Sep 03 08:10:43 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher03.flac&metadata=false Sep 03 08:10:43 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher03.flac Sep 03 08:10:43 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher04.flac in service mpd Sep 03 08:10:43 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher04.flac&metadata=false Sep 03 08:10:43 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher04.flac Sep 03 08:10:43 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/scherLeCoq05.flac in service mpd Sep 03 08:10:43 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FscherLeCoq05.flac&metadata=false Sep 03 08:10:43 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/scherLeCoq05.flac Sep 03 08:10:45 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::ClearQueue Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::stPlaybackTimer Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::serviceStop Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::serviceStop Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::stop Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::clearPlayQueue Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::addQueueItems Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::addQueueItems Sep 03 08:10:45 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:10:45 volumio volumio[2939]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160 Sep 03 08:10:45 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160 in service mpd Sep 03 08:10:45 volumio volumio[2939]: info: Sep 03 08:10:45 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:45 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher01.flac&metadata=false Sep 03 08:10:45 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac Sep 03 08:10:45 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher02.flac&metadata=false Sep 03 08:10:45 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher02.flac Sep 03 08:10:45 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher03.flac&metadata=false Sep 03 08:10:45 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher03.flac Sep 03 08:10:45 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FScher04.flac&metadata=false Sep 03 08:10:45 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher04.flac Sep 03 08:10:45 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=//extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FRimsky%20Korsakov-Scheherazade%2CLe%20Coq%20D'Or-VSOO%2CScherchen%2CBSO%2CLeinsdorf-HDTT-0160%2FscherLeCoq05.flac&metadata=false Sep 03 08:10:45 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/scherLeCoq05.flac Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand stop took 84 milliseconds Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPlay Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::play index 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::play index undefined Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::startPlaybackTimer Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::clearAddPlayTracks USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand status took 21 milliseconds Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand stop took 4 milliseconds Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand clear Sep 03 08:10:45 volumio volumio[2939]: info: Sep 03 08:10:45 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:45 volumio volumio[2939]: info: Sep 03 08:10:45 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:45 volumio volumio[2939]: info: Sep 03 08:10:45 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:45 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 87 milliseconds Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand clear took 88 milliseconds Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand status took 14 milliseconds Sep 03 08:10:45 volumio volumio[2939]: info: sendMpdCommand status took 12 milliseconds Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand add "USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac" Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:45 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rachmaninov Piano Concerto no. 2 - Moderato; Allegro","artist":"Dejan Lazic","album":"Rachmaninov Piano concerto No. 2","uri":"USB/01DC19762C662800/Classic/Rachmaninov-Piano Concerto no.2,-Dejan Lazic,LPO,Kirill Petrenko-Channel-0581 24192/01 - Rachmaninov Piano Concerto no 2 - Moderato Allegro.flac","trackType":"flac"} Sep 03 08:10:45 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: No code Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:45 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: No code Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 03 08:10:45 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState stateService stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: No code Sep 03 08:10:45 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:45 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:45 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:45 volumio volumio[2939]: info: ------------------------------ 264ms Sep 03 08:10:45 volumio volumio[2939]: info: ------------------------------ 157ms Sep 03 08:10:45 volumio volumio[2939]: info: ------------------------------ 155ms Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:46 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:46 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:46 volumio volumio[2939]: error: updateQueue error: null Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:46 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 234ms Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand add "USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac" took 223 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 18ms Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 16ms Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand play Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:46 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces system playlist update Sep 03 08:10:46 volumio volumio[2939]: info: Ignoring MPD Status Update Sep 03 08:10:46 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Sep 03 08:10:46 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 197ms Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand play took 193 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 193ms Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 211ms Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 68 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 32 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 25 milliseconds Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: Sep 03 08:10:46 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::getState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand status Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 103 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 98 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 35 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand status took 29 milliseconds Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseState Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":503,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1554 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus stop Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1854 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:46 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1926 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:46 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 309ms Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 288ms Sep 03 08:10:46 volumio volumio[2939]: info: ------------------------------ 268ms Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 397 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 392 milliseconds Sep 03 08:10:46 volumio volumio[2939]: info: sendMpdCommand playlistinfo took 391 milliseconds Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:46 volumio volumio[2939]: verbose: ControllerMpd::parseTrackInfo Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":677,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1926 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:46 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1990 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:46 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: ControllerMpd::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::servicePushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":704,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1990 Kbps","isStreaming":false,"title":"Scher01.flac","artist":null,"album":null,"uri":"USB/01DC19762C662800/Classic/Rimsky Korsakov-Scheherazade,Le Coq D'Or-VSOO,Scherchen,BSO,Leinsdorf-HDTT-0160/Scher01.flac","trackType":"flac"} Sep 03 08:10:46 volumio volumio[2939]: verbose: CURRENT POSITION 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState stateService play Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::syncState currentStatus play Sep 03 08:10:46 volumio volumio[2939]: info: Received an update from plugin. extracting info from payload Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:46 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:10:46 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:10:46 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:10:47 volumio volumio[2939]: info: ------------------------------ 700ms Sep 03 08:10:47 volumio volumio[2939]: info: ------------------------------ 629ms Sep 03 08:10:47 volumio volumio[2939]: info: ------------------------------ 624ms Sep 03 08:10:47 volumio volumio[2939]: info: touch_display: Setting screensaver timeout to 0 seconds. Sep 03 08:11:03 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:11:03 volumio volumio[2939]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::ClearQueue Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::stop Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::stPlaybackTimer Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::updateTrackBlock Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::getTrackBlock Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::pushState Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:11:03 volumio volumio[2939]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 08:11:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushState Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::serviceStop Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::getTrack 0 Sep 03 08:11:03 volumio volumio[2939]: info: CoreCommandRouter::serviceStop Sep 03 08:11:03 volumio volumio[2939]: info: ControllerMpd::stop Sep 03 08:11:03 volumio volumio[2939]: verbose: ControllerMpd::sendMpdCommand stop Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::clearPlayQueue Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::saveQueue Sep 03 08:11:03 volumio volumio[2939]: info: CoreCommandRouter::volumioPushQueue Sep 03 08:11:03 volumio volumio[2939]: info: CoreStateMachine::addQueueItems Sep 03 08:11:03 volumio volumio[2939]: info: CorePlayQueue::addQueueItems Sep 03 08:11:03 volumio volumio[2939]: info: Preload queue cleared Sep 03 08:11:03 volumio volumio[2939]: info: Adding Item to queue: music-library/USB/01DC19762C662800/Classic/Saint-Saens - Concertos (Yo-Yo Ma, Licad, Cho-Liang Lin) Sep 03 08:11:03 volumio volumio[2939]: info: Exploding uri music-library/USB/01DC19762C662800/Classic/Saint-Saens - Concertos (Yo-Yo Ma, Licad, Cho-Liang Lin) in service mpd Sep 03 08:11:03 volumio volumio[2939]: info: Sep 03 08:11:03 volumio volumio[2939]: ---------------------------- MPD announces state update: player Sep 03 08:11:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Saint-Saens/Cello%20Concerto%20No.1%2C%20Piano%20Concerto%20no.%202%2C%20Violin%20Concerto%20No.%203/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FSaint-Saens%20-%20Concertos%20(Yo-Yo%20Ma%2C%20Licad%2C%20Cho-Liang%20Lin)%2FSaint-Saens%20-%20Cello%20Concerto%20No.1%2C%20Piano%20Concerto%20no.%202%2C%20Violin%20Concerto%20No.%203.cue&metadata=false Sep 03 08:11:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Saint-Saens - Concertos (Yo-Yo Ma, Licad, Cho-Liang Lin)/Saint-Saens - Cello Concerto No.1, Piano Concerto no. 2, Violin Concerto No. 3.cue Sep 03 08:11:03 volumio volumio[2939]: info: ALBUMART /albumart?cacheid=884&web=Saint-Saens/Cello%20Concerto%20No.1%2C%20Piano%20Concerto%20no.%202%2C%20Violin%20Concerto%20No.%203/extralarge&path=%2Fmnt%2FUSB%2F01DC19762C662800%2FClassic%2FSaint-Saens%20-%20Concertos%20(Yo-Yo%20Ma%2C%20Licad%2C%20Cho-Liang%20Lin)%2FSaint-Saens%20-%20Cello%20Concerto%20No.1%2C%20Piano%20Concerto%20no.%202%2C%20Violin%20Concerto%20No.%203.cue&metadata=false Sep 03 08:11:03 volumio volumio[2939]: info: URI /mnt/USB/01DC19762C662800/Classic/Saint-Saens - Concertos (Yo-Yo Ma, Licad, Cho-Liang Lin)/Saint-Saens - Cello Concerto No.1, Piano Concerto no. 2, Violin Concerto No. 3.cue Sep 03 08:11:03 volumio volumio[2939]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 08:11:03 volumio volumio[2939]: Error: Unable to resolve or reject the same promise twice Sep 03 08:11:03 volumio volumio[2939]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Sep 03 08:11:03 volumio volumio[2939]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Sep 03 08:11:03 volumio volumio[2939]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Sep 03 08:11:03 volumio volumio[2939]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Sep 03 08:11:03 volumio volumio[2939]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Sep 03 08:11:03 volumio volumio[2939]: at Socket.emit (events.js:315:20) Sep 03 08:11:03 volumio volumio[2939]: at addChunk (internal/streams/readable.js:309:12) Sep 03 08:11:03 volumio volumio[2939]: at readableAddChunk (internal/streams/readable.js:280:11) Sep 03 08:11:03 volumio volumio[2939]: at Socket.Readable.push (internal/streams/readable.js:223:10) Sep 03 08:11:03 volumio volumio[2939]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Sep 03 08:11:03 volumio volumio[2939]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 08:11:05 volumio sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 08:10 Sep 03 08:11:05 volumio sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"