Oct 02 17:18:01 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:18:01 volumio volumio[1369]: info: CURURI: music-library/USB/ESD-USB/2014 - New Eyes Oct 02 17:18:01 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/01 - Clean Bandit - Mozart's House.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/02 - Clean Bandit - Extraordinary.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/04 - Clean Bandit - Rather Be.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/05 - Clean Bandit - A&E.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/06 - Clean Bandit - Come Over.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/07 - Clean Bandit - Cologne.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/08 - Clean Bandit - Telephone Banking.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/09 - Clean Bandit - Up Again.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/10 - Clean Bandit - Heart On Fire.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/11 - Clean Bandit - New Eyes.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/12 - Clean Bandit - Birch.flac Oct 02 17:18:01 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2014 - New Eyes/13 - Clean Bandit - Outro Movement III.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/01 - Clean Bandit - Mozart's House.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F01%20-%20Clean%20Bandit%20-%20Mozart's%20House.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/01 - Clean Bandit - Mozart's House.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/02 - Clean Bandit - Extraordinary.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F02%20-%20Clean%20Bandit%20-%20Extraordinary.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/02 - Clean Bandit - Extraordinary.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F03%20-%20Clean%20Bandit%20-%20Dust%20Clears.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/04 - Clean Bandit - Rather Be.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F04%20-%20Clean%20Bandit%20-%20Rather%20Be.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/04 - Clean Bandit - Rather Be.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/05 - Clean Bandit - A&E.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F05%20-%20Clean%20Bandit%20-%20A%26E.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/05 - Clean Bandit - A&E.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/06 - Clean Bandit - Come Over.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F06%20-%20Clean%20Bandit%20-%20Come%20Over.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/06 - Clean Bandit - Come Over.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/07 - Clean Bandit - Cologne.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F07%20-%20Clean%20Bandit%20-%20Cologne.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/07 - Clean Bandit - Cologne.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/08 - Clean Bandit - Telephone Banking.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F08%20-%20Clean%20Bandit%20-%20Telephone%20Banking.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/08 - Clean Bandit - Telephone Banking.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/09 - Clean Bandit - Up Again.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F09%20-%20Clean%20Bandit%20-%20Up%20Again.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/09 - Clean Bandit - Up Again.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/10 - Clean Bandit - Heart On Fire.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F10%20-%20Clean%20Bandit%20-%20Heart%20On%20Fire.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/10 - Clean Bandit - Heart On Fire.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/11 - Clean Bandit - New Eyes.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F11%20-%20Clean%20Bandit%20-%20New%20Eyes.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/11 - Clean Bandit - New Eyes.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/12 - Clean Bandit - Birch.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F12%20-%20Clean%20Bandit%20-%20Birch.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/12 - Clean Bandit - Birch.flac Oct 02 17:18:02 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2014 - New Eyes/13 - Clean Bandit - Outro Movement III.flac in service mpd Oct 02 17:18:02 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Clean%20Bandit/New%20Eyes/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2014%20-%20New%20Eyes%2F13%20-%20Clean%20Bandit%20-%20Outro%20Movement%20III.flac&metadata=false Oct 02 17:18:02 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2014 - New Eyes/13 - Clean Bandit - Outro Movement III.flac Oct 02 17:18:04 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:18:04 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:04 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/01 - Clean Bandit - Mozart's House.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/01 - Clean Bandit - Mozart's House.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/02 - Clean Bandit - Extraordinary.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/02 - Clean Bandit - Extraordinary.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::play index 2 Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:04 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/04 - Clean Bandit - Rather Be.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/04 - Clean Bandit - Rather Be.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/05 - Clean Bandit - A&E.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/05 - Clean Bandit - A&E.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/06 - Clean Bandit - Come Over.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/06 - Clean Bandit - Come Over.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/07 - Clean Bandit - Cologne.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/07 - Clean Bandit - Cologne.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/08 - Clean Bandit - Telephone Banking.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/08 - Clean Bandit - Telephone Banking.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/09 - Clean Bandit - Up Again.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/09 - Clean Bandit - Up Again.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/10 - Clean Bandit - Heart On Fire.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/10 - Clean Bandit - Heart On Fire.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/11 - Clean Bandit - New Eyes.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/11 - Clean Bandit - New Eyes.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/12 - Clean Bandit - Birch.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/12 - Clean Bandit - Birch.flac Oct 02 17:18:04 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2014 - New Eyes/13 - Clean Bandit - Outro Movement III.flac Oct 02 17:18:04 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2014 - New Eyes/13 - Clean Bandit - Outro Movement III.flac Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:04 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand stop took 12 milliseconds Oct 02 17:18:04 volumio volumio[1369]: info: Oct 02 17:18:04 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:04 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand stop took 6 milliseconds Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:18:04 volumio volumio[1369]: info: Oct 02 17:18:04 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:04 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:04 volumio volumio[1369]: info: Oct 02 17:18:04 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:04 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:04 volumio volumio[1369]: info: Oct 02 17:18:04 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:04 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand clear took 1 milliseconds Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:04 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac" Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:04 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:04 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:04 volumio volumio[1369]: 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} Oct 02 17:18:04 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:04 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:04 volumio volumio[1369]: info: No code Oct 02 17:18:04 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:04 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: 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} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: No code Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 9ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:05 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:05 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:05 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:05 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 11ms Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac" took 10 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:18:05 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 12ms Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:05 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:05 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 4ms Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand play took 4 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 3ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 3ms Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 25 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 25ms Oct 02 17:18:05 volumio volumio[1369]: info: Oct 02 17:18:05 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:05 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"122 Kbps","isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:05 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"390 Kbps","isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:05 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 33ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 32ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 17:18:05 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:05 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"Dust Clears","artist":"Clean Bandit","album":"New Eyes","uri":"USB/ESD-USB/2014 - New Eyes/03 - Clean Bandit - Dust Clears.flac","trackType":"flac"} Oct 02 17:18:05 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:05 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:05 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:05 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 12ms Oct 02 17:18:05 volumio volumio[1369]: info: ------------------------------ 13ms Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:05 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:18:19 volumio volumio[1369]: info: CURURI: music-library/USB/ESD-USB/2012 - Black Radio [flac] Oct 02 17:18:19 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:19 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F01%20-%20%20Lift%20OffMic%20Check%20(feat.%20Shafiq%20Husayn).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F02%20-%20%20Afro%20Blue%20(feat.%20Erykah%20Badu).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F03%20-%20%20Cherish%20The%20Day%20(feat.%20Lalah%20Hathaway).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F04%20-%20%20Always%20Shine%20(feat.%20Lupe%20Fiasco%20and%20Bilal).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F05%20-%20%20Gonna%20Be%20Alright%20(F.T.B.)%20(feat.%20Ledisi).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F06%20-%20%20Move%20Love%20(feat.%20KING).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F07%20-%20%20Ah%20Yeah%20(feat.%20Musiq%20Soulchild%20and%20Chrisette%20Michele).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F08%20-%20%20Consequence%20Of%20Jealously%20(feat.%20Meshell%20Ndegeocello).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F09%20-%20%20Why%20Do%20We%20Try%20(feat.%20Stokely).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F10%20-%20%20Black%20Radio%20(feat.%20Mos%20Def).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F11%20-%20%20Letter%20to%20Hermoine%20(feat.%20Bilal).flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:19 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac in service mpd Oct 02 17:18:19 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Robert%20Glasper/Black%20Radio/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2012%20-%20Black%20Radio%20%5Bflac%5D%2F12%20-%20%20Smells%20Like%20Teen%20Spirit.flac&metadata=false Oct 02 17:18:19 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:21 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:21 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::play index 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:21 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:21 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:21 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand stop took 11 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand stop took 5 milliseconds Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand clear took 2 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac" Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: 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} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: No code Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: 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} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: No code Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 11ms Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 12ms Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac" took 12 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 2ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:18:21 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 16ms Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:21 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand play took 6 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 20 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 21 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 20 milliseconds Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: Oct 02 17:18:21 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:21 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":756,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"161 Kbps","isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:21 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 27ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 29ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 29ms Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:21 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"173 Kbps","isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:21 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"173 Kbps","isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:21 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":353,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"173 Kbps","isStreaming":false,"title":"Cherish The Day (feat. Lalah Hathaway)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac","trackType":"flac"} Oct 02 17:18:21 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:21 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:21 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:21 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 16ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:18:21 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:21 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:24 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/01 - Lift OffMic Check (feat. Shafiq Husayn).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/02 - Afro Blue (feat. Erykah Badu).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/03 - Cherish The Day (feat. Lalah Hathaway).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/04 - Always Shine (feat. Lupe Fiasco and Bilal).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/05 - Gonna Be Alright (F.T.B.) (feat. Ledisi).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/06 - Move Love (feat. KING).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/07 - Ah Yeah (feat. Musiq Soulchild and Chrisette Michele).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/08 - Consequence Of Jealously (feat. Meshell Ndegeocello).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/09 - Why Do We Try (feat. Stokely).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::play index 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:24 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/11 - Letter to Hermoine (feat. Bilal).flac Oct 02 17:18:24 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:24 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2012 - Black Radio [flac]/12 - Smells Like Teen Spirit.flac Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand stop took 11 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand stop took 3 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand clear took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac" Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: 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} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: No code Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: 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} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: No code Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 7ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:18:24 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac" took 7 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:18:24 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand play took 5 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 4ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 4ms Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:24 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 22 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 22 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 22 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: Oct 02 17:18:24 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 24ms Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:24 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"342 Kbps","isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:24 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 30ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 29ms Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:24 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"538 Kbps","isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:24 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"538 Kbps","isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:24 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"538 Kbps","isStreaming":false,"title":"Black Radio (feat. Mos Def)","artist":"Robert Glasper","album":"Black Radio","uri":"USB/ESD-USB/2012 - Black Radio [flac]/10 - Black Radio (feat. Mos Def).flac","trackType":"flac"} Oct 02 17:18:24 volumio volumio[1369]: verbose: CURRENT POSITION 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:24 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:24 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:24 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 14ms Oct 02 17:18:24 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:24 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:33 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:18:33 volumio volumio[1369]: info: CURURI: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL] Oct 02 17:18:33 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 01 - 20 Feet Tall.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 02 - Window Seat.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 04 - Turn Me Away (Get MuNNY).flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 05 - Gone Baby, Don't Be Long.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 06 - Umm Hmm.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 07 - Love.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 08 - You Loving Me (Session).flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 09 - Fall in Love (Your Funeral).flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 10 - Incense.flac Oct 02 17:18:33 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 11 - Out of Mind, Just in Time .flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 01 - 20 Feet Tall.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2001%20-%2020%20Feet%20Tall.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 01 - 20 Feet Tall.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 02 - Window Seat.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2002%20-%20Window%20Seat.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 02 - Window Seat.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2003%20-%20Agitation.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 04 - Turn Me Away (Get MuNNY).flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2004%20-%20Turn%20Me%20Away%20(Get%20MuNNY).flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 04 - Turn Me Away (Get MuNNY).flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 05 - Gone Baby, Don't Be Long.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2005%20-%20Gone%20Baby%2C%20Don't%20Be%20Long.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 05 - Gone Baby, Don't Be Long.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 06 - Umm Hmm.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2006%20-%20Umm%20Hmm.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 06 - Umm Hmm.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 07 - Love.flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2007%20-%20Love.flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 07 - Love.flac Oct 02 17:18:33 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 08 - You Loving Me (Session).flac in service mpd Oct 02 17:18:33 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2008%20-%20You%20Loving%20Me%20(Session).flac&metadata=false Oct 02 17:18:33 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 08 - You Loving Me (Session).flac Oct 02 17:18:34 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 09 - Fall in Love (Your Funeral).flac in service mpd Oct 02 17:18:34 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2009%20-%20Fall%20in%20Love%20(Your%20Funeral).flac&metadata=false Oct 02 17:18:34 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 09 - Fall in Love (Your Funeral).flac Oct 02 17:18:34 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 10 - Incense.flac in service mpd Oct 02 17:18:34 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2010%20-%20Incense.flac&metadata=false Oct 02 17:18:34 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 10 - Incense.flac Oct 02 17:18:34 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 11 - Out of Mind, Just in Time .flac in service mpd Oct 02 17:18:34 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Erykah%20Badu/New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F2010%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%2FErykah%20Badu%20-%20New%20Amerykah%20Part%20Two%20(Return%20of%20the%20Ankh)%20%5BVINYL%5D%20-%2011%20-%20Out%20of%20Mind%2C%20Just%20in%20Time%20.flac&metadata=false Oct 02 17:18:34 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 11 - Out of Mind, Just in Time .flac Oct 02 17:18:35 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 9 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:35 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 01 - 20 Feet Tall.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 01 - 20 Feet Tall.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 02 - Window Seat.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 02 - Window Seat.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::play index 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:18:35 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 04 - Turn Me Away (Get MuNNY).flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 04 - Turn Me Away (Get MuNNY).flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 05 - Gone Baby, Don't Be Long.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 05 - Gone Baby, Don't Be Long.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 06 - Umm Hmm.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 06 - Umm Hmm.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 07 - Love.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 07 - Love.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 08 - You Loving Me (Session).flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 08 - You Loving Me (Session).flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 09 - Fall in Love (Your Funeral).flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 09 - Fall in Love (Your Funeral).flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 10 - Incense.flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 10 - Incense.flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 11 - Out of Mind, Just in Time .flac Oct 02 17:18:35 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 11 - Out of Mind, Just in Time .flac Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).cue Oct 02 17:18:35 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).cue in service mpd Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).cue Oct 02 17:18:35 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).cue in service mpd Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).releaser.cue Oct 02 17:18:35 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).releaser.cue in service mpd Oct 02 17:18:35 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).releaser.cue Oct 02 17:18:35 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/New Amerykah Part Two (Return of the Ankh).releaser.cue in service mpd Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand stop took 28 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand stop took 5 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand clear took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac" Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: 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} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: No code Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: 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} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: No code Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 7ms Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac" took 8 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:18:35 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 9ms Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:18:35 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand play took 5 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 4ms Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 25ms Oct 02 17:18:35 volumio volumio[1369]: info: Oct 02 17:18:35 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:35 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:35 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 29ms Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 29ms Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand status took 5 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":206,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"380 Kbps","isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:35 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"295 Kbps","isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:35 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 11ms Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 17:18:35 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:18:35 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":93,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"295 Kbps","isStreaming":false,"title":"Agitation","artist":"Erykah Badu","album":"New Amerykah Part Two (Return of the Ankh) [VINYL]","uri":"USB/ESD-USB/2010 New Amerykah Part Two (Return of the Ankh) [VINYL]/Erykah Badu - New Amerykah Part Two (Return of the Ankh) [VINYL] - 03 - Agitation.flac","trackType":"flac"} Oct 02 17:18:35 volumio volumio[1369]: verbose: CURRENT POSITION 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:18:35 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:18:35 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:18:35 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:18:35 volumio volumio[1369]: info: ------------------------------ 17ms Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:18:35 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:08 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:19:08 volumio volumio[1369]: info: CURURI: music-library/USB/ESD-USB/1997 sennen kairoh @192k Oct 02 17:19:08 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:08 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 in service mpd Oct 02 17:19:08 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F01%20Sennen%20Kairoh%20-%20Sennen%20no%20Inor.mp3&metadata=false Oct 02 17:19:08 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F02%20Hajimari%20no%20Asa.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F03%20Mirai%20no%20Hitomi.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F04%20Hitorishizuka.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F05%20Kaeranu%20Hibi.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F06%20Tabiji.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F07%20Tukiakari%20no%20Suna%20no%20Nakani.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F08%20Shikai.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F09%20Omoi.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:09 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 in service mpd Oct 02 17:19:09 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Himekami/Sennen%20Kairoh/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1997%20%20sennen%20kairoh%20%40192k%2F10%20Ano%20Sora%20no%20Shita%20ni.mp3&metadata=false Oct 02 17:19:09 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 2 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:19:14 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::play index 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:19:14 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand stop took 10 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand stop took 5 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand clear took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3" Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: 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} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: No code Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: 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} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: No code Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3" took 10 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 2ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 2ms Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:19:14 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 13ms Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:14 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand play took 5 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 4ms Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 24 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 25 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 25 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: info: Oct 02 17:19:14 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 27ms Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:14 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:14 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:14 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 33ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 34ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 17:19:14 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:14 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hajimari no Asa","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3","trackType":"mp3"} Oct 02 17:19:14 volumio volumio[1369]: verbose: CURRENT POSITION 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:14 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:14 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:14 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 17ms Oct 02 17:19:14 volumio volumio[1369]: info: ------------------------------ 16ms Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:14 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::ClearQueue Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::stPlaybackTimer Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::serviceStop Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::serviceStop Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::stop Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::clearPlayQueue Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:19:19 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/01 Sennen Kairoh - Sennen no Inor.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/02 Hajimari no Asa.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/03 Mirai no Hitomi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 17:19:19 volumio volumio[1369]: info: Received Get System Info Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 17:19:19 volumio volumio[1369]: info: Discovery: Getting this device information Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioGetState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 1 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPlay Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::play index 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::addQueueItems Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::addQueueItems Oct 02 17:19:19 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/05 Kaeranu Hibi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/06 Tabiji.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/07 Tukiakari no Suna no Nakani.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/08 Shikai.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/09 Omoi.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Adding Item to queue: music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: Using cached record of: music-library/USB/ESD-USB/1997 sennen kairoh @192k/10 Ano Sora no Shita ni.mp3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushQueue Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::saveQueue Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::play index undefined Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::updateTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrackBlock Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::startPlaybackTimer Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::clearAddPlayTracks USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3 Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand stop took 11 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand stop took 4 milliseconds Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand status took 2 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand clear took 2 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand add "USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3" Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: 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} Oct 02 17:19:19 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: No code Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: 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} Oct 02 17:19:19 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::syncState stateService stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: No code Oct 02 17:19:19 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:19 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:19 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 8ms Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: error: updateQueue error: null Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 10ms Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand add "USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3" took 9 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 1ms Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand play Oct 02 17:19:19 volumio volumio[1369]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 11ms Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces system playlist update Oct 02 17:19:19 volumio volumio[1369]: info: Ignoring MPD Status Update Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 7ms Oct 02 17:19:19 volumio volumio[1369]: info: sendMpdCommand play took 6 milliseconds Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:19:19 volumio volumio[1369]: info: ------------------------------ 5ms Oct 02 17:19:19 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:19 volumio volumio[1369]: info: Oct 02 17:19:19 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:19 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:19 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:20 volumio volumio[1369]: info: Oct 02 17:19:20 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 21 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 22 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 22 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 1 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus stop Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 22ms Oct 02 17:19:20 volumio volumio[1369]: info: Oct 02 17:19:20 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:20 volumio volumio[1369]: info: Oct 02 17:19:20 volumio volumio[1369]: ---------------------------- MPD announces state update: player Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::getState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand status Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand status took 0 milliseconds Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseState Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:20 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":756,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:20 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:20 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 33ms Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 33ms Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 12ms Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 17:19:20 volumio volumio[1369]: info: sendMpdCommand playlistinfo took 10 milliseconds Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: verbose: ControllerMpd::parseTrackInfo Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:20 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: ControllerMpd::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::servicePushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Hitorishizuka","artist":"Himekami","album":"Sennen Kairoh","uri":"USB/ESD-USB/1997 sennen kairoh @192k/04 Hitorishizuka.mp3","trackType":"mp3"} Oct 02 17:19:20 volumio volumio[1369]: verbose: CURRENT POSITION 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState stateService play Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::syncState currentStatus play Oct 02 17:19:20 volumio volumio[1369]: info: Received an update from plugin. extracting info from payload Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: CoreStateMachine::pushState Oct 02 17:19:20 volumio volumio[1369]: info: CorePlayQueue::getTrack 3 Oct 02 17:19:20 volumio volumio[1369]: info: CoreCommandRouter::volumioPushState Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:19:20 volumio volumio[1369]: info: ------------------------------ 15ms Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:20 volumio volumio[1369]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Oct 02 17:19:28 volumio volumio[1369]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 17:19:28 volumio volumio[1369]: info: CURURI: music-library/USB/ESD-USB/1999 the melody at night, with you [flac] Oct 02 17:19:28 volumio volumio[1369]: info: Preload queue cleared Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/02 - I Got It Bad and That Ain't Good.flac Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/04 - Someone to Watch Over Me.flac Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/06 - Blame It on My Youth - Meditation.flac Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/07 - Something to Remember You By.flac Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/10 - I'm Through with Love.flac Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/The Melody at Night, with You.FLAC.CUE Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/the melody at night, with you.m3u Oct 02 17:19:28 volumio volumio[1369]: info: Preloading song: music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/The Melody at Night, with You.WAV.CUE Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/02 - I Got It Bad and That Ain't Good.flac in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20at%20Night%2C%20with%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2F02%20-%20I%20Got%20It%20Bad%20and%20That%20Ain't%20Good.flac&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/02 - I Got It Bad and That Ain't Good.flac Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/04 - Someone to Watch Over Me.flac in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20at%20Night%2C%20with%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2F04%20-%20Someone%20to%20Watch%20Over%20Me.flac&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/04 - Someone to Watch Over Me.flac Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/06 - Blame It on My Youth - Meditation.flac in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20at%20Night%2C%20with%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2F06%20-%20Blame%20It%20on%20My%20Youth%20-%20Meditation.flac&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/06 - Blame It on My Youth - Meditation.flac Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/07 - Something to Remember You By.flac in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20at%20Night%2C%20with%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2F07%20-%20Something%20to%20Remember%20You%20By.flac&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/07 - Something to Remember You By.flac Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/10 - I'm Through with Love.flac in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20at%20Night%2C%20with%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2F10%20-%20I'm%20Through%20with%20Love.flac&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/10 - I'm Through with Love.flac Oct 02 17:19:28 volumio volumio[1369]: info: Exploding uri music-library/USB/ESD-USB/1999 the melody at night, with you [flac]/The Melody at Night, with You.FLAC.CUE in service mpd Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20At%20Night%2C%20With%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2FThe%20Melody%20at%20Night%2C%20with%20You.FLAC.CUE&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/The Melody at Night, with You.FLAC.CUE Oct 02 17:19:28 volumio volumio[1369]: info: ALBUMART /albumart?cacheid=987&web=Keith%20Jarrett/The%20Melody%20At%20Night%2C%20With%20You/extralarge&path=%2Fmnt%2FUSB%2FESD-USB%2F1999%20the%20melody%20at%20night%2C%20with%20you%20%5Bflac%5D%2FThe%20Melody%20at%20Night%2C%20with%20You.FLAC.CUE&metadata=false Oct 02 17:19:28 volumio volumio[1369]: info: URI /mnt/USB/ESD-USB/1999 the melody at night, with you [flac]/The Melody at Night, with You.FLAC.CUE Oct 02 17:19:28 volumio volumio[1369]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:19:28 volumio volumio[1369]: Error: Unable to resolve or reject the same promise twice Oct 02 17:19:28 volumio volumio[1369]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 02 17:19:28 volumio volumio[1369]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 02 17:19:28 volumio volumio[1369]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 02 17:19:28 volumio volumio[1369]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 02 17:19:28 volumio volumio[1369]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 02 17:19:28 volumio volumio[1369]: at Socket.emit (node:events:514:28) Oct 02 17:19:28 volumio volumio[1369]: at addChunk (node:internal/streams/readable:343:12) Oct 02 17:19:28 volumio volumio[1369]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 02 17:19:28 volumio volumio[1369]: at Readable.push (node:internal/streams/readable:253:10) Oct 02 17:19:28 volumio volumio[1369]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 02 17:19:28 volumio volumio[1369]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 17:19:28 volumio sudo[13894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 17:18' Oct 02 17:19:28 volumio sudo[13894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"