-- Logs begin at Wed 2024-04-03 19:20:03 UTC, end at Fri 2024-04-12 12:56:34 UTC. -- Apr 12 12:55:00 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: [1712926500743] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:00 volumio volumio[842]: info: Adding CUE individual entry: 12 USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:00 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:00 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue@12 Apr 12 12:55:00 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue@12 in service mpd Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::play index 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 15 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 15 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand stop took 24 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand stop took 1 milliseconds Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand clear took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue" Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":11,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Can't Leave You","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: No code Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: 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} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: No code Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: 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} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: No code Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 50ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 47ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 47ms Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 49ms Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue" took 49 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 4ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 3ms Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:00 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 24ms Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand play took 22 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 22ms Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 19ms Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 6 milliseconds Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: Apr 12 12:55:00 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:00 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:00 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:00 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 28ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 50ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 48ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 48ms Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 12 12:55:00 volumio volumio[842]: info: sendMpdCommand playlistinfo took 36 milliseconds Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":846,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"858 Kbps","isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:00 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":12,"seek":846,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"844 Kbps","isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:00 volumio volumio[842]: verbose: CURRENT POSITION 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:00 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:00 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:00 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 66ms Apr 12 12:55:00 volumio volumio[842]: info: ------------------------------ 66ms Apr 12 12:55:03 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: [1712926503507] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:03 volumio volumio[842]: info: Adding CUE individual entry: 13 USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:03 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:03 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue@13 Apr 12 12:55:03 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue@13 in service mpd Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::play index 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 16 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand stop took 20 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand stop took 3 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand clear took 3 milliseconds Apr 12 12:55:03 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:03 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue" Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 7ms Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 6ms Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":12,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Prisoner Of Love","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:03 volumio volumio[842]: verbose: CURRENT POSITION 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: No code Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 30ms Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 27ms Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.cue" took 29 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 7ms Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 7ms Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:03 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 35ms Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand play took 30 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 30ms Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 29ms Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: Apr 12 12:55:03 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:03 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:03 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":13,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Petite Fleur","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:03 volumio volumio[842]: verbose: CURRENT POSITION 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":13,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Petite Fleur","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:03 volumio volumio[842]: verbose: CURRENT POSITION 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:03 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":13,"seek":0,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Petite Fleur","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:03 volumio volumio[842]: verbose: CURRENT POSITION 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:03 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:03 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:03 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 23ms Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 46ms Apr 12 12:55:03 volumio volumio[842]: info: ------------------------------ 46ms Apr 12 12:55:25 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 12:55:25 volumio volumio[842]: info: CURURI: music-library/USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U) Apr 12 12:55:25 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:25 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac Apr 12 12:55:25 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac in service mpd Apr 12 12:55:25 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=//extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FLeonard%20Cohen%20-%20Popular%20Problems%20-%202014%20(Sony%20Music%2C%2088875014292%2C%20E.U)%2FLeonard%20Cohen%20-%20Popular%20Problems.flac&metadata=false Apr 12 12:55:25 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac Apr 12 12:55:35 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: [1712926535031] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:35 volumio volumio[842]: info: Adding CUE individual entry: 0 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:35 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:35 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@0 Apr 12 12:55:35 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@0 in service mpd Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::play index 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 17 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand stop took 21 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand stop took 3 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand clear took 3 milliseconds Apr 12 12:55:35 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:35 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 7ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 6ms Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":13,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Petite Fleur","artist":"Oscar Benton","album":"Best Of","uri":"USB/AC42DF7B42DF492A/Oscar Benton - Best Of '1998/Oscar Benton - Best Of.FLAC","trackType":"FLAC"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: No code Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 28ms Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 25ms Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 24 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 4ms Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:35 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 18ms Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand play took 16 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 16ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 15ms Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 6 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: Apr 12 12:55:35 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":411,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"279 Kbps","isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:35 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:35 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 24ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 36ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 36ms Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand status took 30 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"418 Kbps","isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:35 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"418 Kbps","isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:35 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 58ms Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 55ms Apr 12 12:55:35 volumio volumio[842]: info: sendMpdCommand playlistinfo took 40 milliseconds Apr 12 12:55:35 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:35 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":205,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"418 Kbps","isStreaming":false,"title":"Slow","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:35 volumio volumio[842]: verbose: CURRENT POSITION 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:35 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:35 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:35 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:35 volumio volumio[842]: info: ------------------------------ 83ms Apr 12 12:55:36 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:36 volumio volumio[842]: info: Apr 12 12:55:36 volumio volumio[842]: [1712926536930] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:36 volumio volumio[842]: info: Adding CUE individual entry: 1 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:36 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:36 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@1 Apr 12 12:55:36 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@1 in service mpd Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::play index 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 18 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand stop took 24 milliseconds Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:36 volumio volumio[842]: info: Apr 12 12:55:36 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand stop took 1 milliseconds Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:36 volumio volumio[842]: info: Apr 12 12:55:36 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:36 volumio volumio[842]: info: Apr 12 12:55:36 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:36 volumio volumio[842]: info: Apr 12 12:55:36 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:36 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand clear took 3 milliseconds Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:36 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:36 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: 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} Apr 12 12:55:36 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: No code Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: 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} Apr 12 12:55:36 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:36 volumio volumio[842]: info: No code Apr 12 12:55:36 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:36 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:36 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 42ms Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 41ms Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:37 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:37 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:37 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:37 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 45ms Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 44 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 44 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:37 volumio volumio[842]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 50ms Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:37 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:37 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 23ms Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand play took 21 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 20ms Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 17ms Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:37 volumio volumio[842]: info: Apr 12 12:55:37 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Almost like the Blues","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:37 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Almost like the Blues","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:37 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:37 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Almost like the Blues","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:37 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:37 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Almost like the Blues","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:37 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:37 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 40ms Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 63ms Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 61ms Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 61ms Apr 12 12:55:37 volumio volumio[842]: info: sendMpdCommand playlistinfo took 53 milliseconds Apr 12 12:55:37 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:37 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":208,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Almost like the Blues","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:37 volumio volumio[842]: verbose: CURRENT POSITION 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:37 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:37 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:37 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:37 volumio volumio[842]: info: ------------------------------ 67ms Apr 12 12:55:37 volumio ntpd[659]: Soliciting pool server 2606:4700:f1::123 Apr 12 12:55:38 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: [1712926538628] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:38 volumio volumio[842]: info: Adding CUE individual entry: 2 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:38 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:38 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@2 Apr 12 12:55:38 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@2 in service mpd Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::play index 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 19 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand stop took 17 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand stop took 1 milliseconds Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand clear took 2 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: 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} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: No code Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: 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} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: No code Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 32ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 31ms Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 36ms Apr 12 12:55:38 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 36 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 3ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 39ms Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:38 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 18ms Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand play took 16 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 16ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 15ms Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 6 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: Apr 12 12:55:38 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 26 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 25 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 23 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand status took 22 milliseconds Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:38 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":0,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:38 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 43ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 57ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 57ms Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 28 milliseconds Apr 12 12:55:38 volumio volumio[842]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":846,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"478 Kbps","isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:38 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":846,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"506 Kbps","isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:38 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":2,"seek":846,"duration":279,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"492 Kbps","isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:38 volumio volumio[842]: verbose: CURRENT POSITION 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:38 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:38 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:38 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 110ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 107ms Apr 12 12:55:38 volumio volumio[842]: info: ------------------------------ 106ms Apr 12 12:55:40 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: [1712926540164] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:40 volumio volumio[842]: info: Adding CUE individual entry: 3 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:40 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:40 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@3 Apr 12 12:55:40 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@3 in service mpd Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::play index 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 20 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand stop took 27 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand stop took 1 milliseconds Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand clear took 4 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":2,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Samson in New Orleans","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: No code Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: 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} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: No code Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: 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} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: No code Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 65ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 59ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 59ms Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 64ms Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 61 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:40 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 57ms Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand play took 54 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 54ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 52ms Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: Apr 12 12:55:40 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 8 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand status took 7 milliseconds Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:40 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:40 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:40 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 34ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 55ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 55ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 54ms Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 12 12:55:40 volumio volumio[842]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:40 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":3,"seek":846,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"716 Kbps","isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:40 volumio volumio[842]: verbose: CURRENT POSITION 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:40 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:40 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:40 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 68ms Apr 12 12:55:40 volumio volumio[842]: info: ------------------------------ 68ms Apr 12 12:55:46 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: [1712926546569] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:46 volumio volumio[842]: info: Adding CUE individual entry: 4 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:46 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:46 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@4 Apr 12 12:55:46 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@4 in service mpd Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::play index 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 21 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand stop took 25 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand stop took 3 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand clear took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:46 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 6ms Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 5ms Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":3,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Street","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:46 volumio volumio[842]: verbose: CURRENT POSITION 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: No code Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 26ms Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 23ms Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 22 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 3ms Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:46 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 22ms Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand play took 20 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 18ms Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 16ms Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: Apr 12 12:55:46 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand status took 1 milliseconds Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:46 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":4,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"496 Kbps","isStreaming":false,"title":"Did I Ever Love You","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:46 volumio volumio[842]: verbose: CURRENT POSITION 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":4,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"496 Kbps","isStreaming":false,"title":"Did I Ever Love You","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:46 volumio volumio[842]: verbose: CURRENT POSITION 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:46 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":4,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"Did I Ever Love You","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:46 volumio volumio[842]: verbose: CURRENT POSITION 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:46 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":4,"seek":846,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"Did I Ever Love You","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:46 volumio volumio[842]: verbose: CURRENT POSITION 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:46 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:46 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:46 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 32ms Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 50ms Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 49ms Apr 12 12:55:46 volumio volumio[842]: info: ------------------------------ 48ms Apr 12 12:55:48 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: [1712926548155] ---------------------------- Client requests add and Play Volumio CUE entry Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , addPlayCue Apr 12 12:55:48 volumio volumio[842]: info: Adding CUE individual entry: 5 USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioAddQueueItems Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::addQueueItems Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::addQueueItems Apr 12 12:55:48 volumio volumio[842]: info: Preload queue cleared Apr 12 12:55:48 volumio volumio[842]: info: Adding Item to queue: cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@5 Apr 12 12:55:48 volumio volumio[842]: info: Exploding uri cue://USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue@5 in service mpd Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPlay Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::play index 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::stop Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::stPlaybackTimer Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::serviceStop Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 22 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::serviceStop Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::stop Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushQueue Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::saveQueue Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::updateTrackBlock Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrackBlock Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand stop took 19 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::play index undefined Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::startPlaybackTimer Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand stop took 4 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 2 milliseconds Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand clear took 3 milliseconds Apr 12 12:55:48 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 9ms Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"stop","position":4,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Did I Ever Love You","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService stop Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: No code Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 29ms Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: error: updateQueue error: null Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 27ms Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand load "USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.cue" took 25 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 8ms Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 7ms Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 34ms Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand play Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces system playlist update Apr 12 12:55:48 volumio volumio[842]: info: Ignoring MPD Status Update Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 55ms Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand play took 51 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 52ms Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 49ms Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 5 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 3 milliseconds Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: info: Apr 12 12:55:48 volumio volumio[842]: ---------------------------- MPD announces state update: player Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::getState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 16 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 15 milliseconds Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand status took 14 milliseconds Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseState Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":5,"seek":846,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"701 Kbps","isStreaming":false,"title":"My Oh My","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus stop Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":5,"seek":846,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"701 Kbps","isStreaming":false,"title":"My Oh My","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:48 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":5,"seek":846,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"701 Kbps","isStreaming":false,"title":"My Oh My","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:48 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":5,"seek":846,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"701 Kbps","isStreaming":false,"title":"My Oh My","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:48 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 45ms Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 65ms Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 62ms Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 62ms Apr 12 12:55:48 volumio volumio[842]: info: sendMpdCommand playlistinfo took 42 milliseconds Apr 12 12:55:48 volumio volumio[842]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:55:48 volumio volumio[842]: info: ControllerMpd::pushState Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::servicePushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: verbose: STATE SERVICE {"status":"play","position":5,"seek":846,"duration":217,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"701 Kbps","isStreaming":false,"title":"My Oh My","artist":"Leonard Cohen","album":"Popular Problems","uri":"USB/AC42DF7B42DF492A/Leonard Cohen - Popular Problems - 2014 (Sony Music, 88875014292, E.U)/Leonard Cohen - Popular Problems.flac","trackType":"flac"} Apr 12 12:55:48 volumio volumio[842]: verbose: CURRENT POSITION 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState stateService play Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::syncState currentStatus play Apr 12 12:55:48 volumio volumio[842]: info: Received an update from plugin. extracting info from payload Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: CoreStateMachine::pushState Apr 12 12:55:48 volumio volumio[842]: info: CorePlayQueue::getTrack 23 Apr 12 12:55:48 volumio volumio[842]: info: CoreCommandRouter::volumioPushState Apr 12 12:55:48 volumio volumio[842]: info: ------------------------------ 71ms Apr 12 12:56:33 volumio volumio[842]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 12 12:56:33 volumio volumio[842]: info: CURURI: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE} Apr 12 12:56:33 volumio volumio[842]: info: Preload queue cleared Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/01 - Beginning Of The End.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/02 - Alright.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/03 - Pennsylvania Blues Tonight.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/04 - I Don't Wanna Hurt You Anymore.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/05 - Electric Arena.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/06 - Gravy Train.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/07 - Figure Of Eight.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/08 - You're The One For Me.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/09 - My Little Heartbreaker.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/10 - Hold Me.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/11 - Saddling Up.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/12 - Bad News.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/13 - Tongue Tied.flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/14 - One By One [bonus track, European edition].flac Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [single WAV].CUE Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [tracks, FLAC].CUE Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [tracks, WAV].CUE Apr 12 12:56:33 volumio volumio[842]: info: Preloading song: music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/Status Quo - In Search Of The Fourth Chord.m3u Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/01 - Beginning Of The End.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F01%20-%20Beginning%20Of%20The%20End.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/01 - Beginning Of The End.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/02 - Alright.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F02%20-%20Alright.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/02 - Alright.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/03 - Pennsylvania Blues Tonight.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F03%20-%20Pennsylvania%20Blues%20Tonight.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/03 - Pennsylvania Blues Tonight.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/04 - I Don't Wanna Hurt You Anymore.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F04%20-%20I%20Don't%20Wanna%20Hurt%20You%20Anymore.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/04 - I Don't Wanna Hurt You Anymore.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/05 - Electric Arena.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F05%20-%20Electric%20Arena.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/05 - Electric Arena.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/06 - Gravy Train.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F06%20-%20Gravy%20Train.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/06 - Gravy Train.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/07 - Figure Of Eight.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F07%20-%20Figure%20Of%20Eight.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/07 - Figure Of Eight.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/08 - You're The One For Me.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F08%20-%20You're%20The%20One%20For%20Me.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/08 - You're The One For Me.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/09 - My Little Heartbreaker.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F09%20-%20My%20Little%20Heartbreaker.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/09 - My Little Heartbreaker.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/10 - Hold Me.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F10%20-%20Hold%20Me.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/10 - Hold Me.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/11 - Saddling Up.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F11%20-%20Saddling%20Up.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/11 - Saddling Up.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/12 - Bad News.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F12%20-%20Bad%20News.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/12 - Bad News.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/13 - Tongue Tied.flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F13%20-%20Tongue%20Tied.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/13 - Tongue Tied.flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/14 - One By One [bonus track, European edition].flac in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2F14%20-%20One%20By%20One%20%5Bbonus%20track%2C%20European%20edition%5D.flac&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/14 - One By One [bonus track, European edition].flac Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [single WAV].CUE in service mpd Apr 12 12:56:33 volumio volumio[842]: info: Exploding uri music-library/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [tracks, FLAC].CUE in service mpd Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2FIn%20Search%20Of%20The%20Fourth%20Chord%20%5Btracks%2C%20FLAC%5D.CUE&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [tracks, FLAC].CUE Apr 12 12:56:33 volumio volumio[842]: info: ALBUMART /albumart?cacheid=327&web=Status%20Quo/In%20Search%20Of%20The%20Fourth%20Chord/extralarge&path=%2Fmnt%2FUSB%2FAC42DF7B42DF492A%2FStatus%20Quo%20-%202007%20-%20In%20Search%20Of%20The%20Fourth%20Chord%20(2007)%20%5BFLAC%5D%20%7B2007%2C%20German%20Edel%2C%200184392ERE%7D%2FIn%20Search%20Of%20The%20Fourth%20Chord%20%5Btracks%2C%20FLAC%5D.CUE&metadata=false Apr 12 12:56:33 volumio volumio[842]: info: URI /mnt/USB/AC42DF7B42DF492A/Status Quo - 2007 - In Search Of The Fourth Chord (2007) [FLAC] {2007, German Edel, 0184392ERE}/In Search Of The Fourth Chord [tracks, FLAC].CUE Apr 12 12:56:33 volumio volumio[842]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:56:34 volumio volumio[842]: Error: Unable to resolve or reject the same promise twice Apr 12 12:56:34 volumio volumio[842]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 12 12:56:34 volumio volumio[842]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 12 12:56:34 volumio volumio[842]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 12 12:56:34 volumio volumio[842]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 12 12:56:34 volumio volumio[842]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 12 12:56:34 volumio volumio[842]: at Socket.emit (events.js:315:20) Apr 12 12:56:34 volumio volumio[842]: at addChunk (internal/streams/readable.js:309:12) Apr 12 12:56:34 volumio volumio[842]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 12 12:56:34 volumio volumio[842]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 12 12:56:34 volumio volumio[842]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 12 12:56:34 volumio volumio[842]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:56:34 volumio sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-12 12:55 Apr 12 12:56:34 volumio sudo[2333]: 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"