-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Fri 2025-12-26 18:39:05 JST. -- Dec 26 18:38:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 26 18:38:11 volumio kernel: hwmon hwmon1: Voltage normalised Dec 26 18:38:32 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 18:38:32 volumio volumio[1002]: info: CURURI: artists://Sarah%20McLachlan Dec 26 18:38:32 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/04 Adia.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/05 Do What You Have to Do.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/06 Witness.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/07 Angel.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/08 Black & White.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/09 Full of Grace.flac Dec 26 18:38:32 volumio volumio[1002]: info: Preloading song: music-library/USB/music/Sarah McLachlan/Surfacing/10 Last Dance.flac Dec 26 18:38:32 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac in service mpd Dec 26 18:38:32 volumio volumio[1002]: info: No valid Plugin REST Endpoint Dec 26 18:38:32 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F01%20Building%20a%20Mystery.flac&metadata=false Dec 26 18:38:32 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:32 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac in service mpd Dec 26 18:38:32 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F02%20I%20Love%20You.flac&metadata=false Dec 26 18:38:32 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac Dec 26 18:38:32 volumio volumio[1002]: info: No valid Plugin REST Endpoint Dec 26 18:38:32 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac in service mpd Dec 26 18:38:32 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F03%20Sweet%20Surrender.flac&metadata=false Dec 26 18:38:32 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac Dec 26 18:38:32 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/04 Adia.flac in service mpd Dec 26 18:38:32 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F04%20Adia.flac&metadata=false Dec 26 18:38:32 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/04 Adia.flac Dec 26 18:38:32 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/05 Do What You Have to Do.flac in service mpd Dec 26 18:38:32 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F05%20Do%20What%20You%20Have%20to%20Do.flac&metadata=false Dec 26 18:38:32 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/05 Do What You Have to Do.flac Dec 26 18:38:33 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/06 Witness.flac in service mpd Dec 26 18:38:33 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F06%20Witness.flac&metadata=false Dec 26 18:38:33 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/06 Witness.flac Dec 26 18:38:33 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/07 Angel.flac in service mpd Dec 26 18:38:33 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F07%20Angel.flac&metadata=false Dec 26 18:38:33 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/07 Angel.flac Dec 26 18:38:33 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/08 Black & White.flac in service mpd Dec 26 18:38:33 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F08%20Black%20%26%20White.flac&metadata=false Dec 26 18:38:33 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/08 Black & White.flac Dec 26 18:38:33 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/09 Full of Grace.flac in service mpd Dec 26 18:38:33 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F09%20Full%20of%20Grace.flac&metadata=false Dec 26 18:38:33 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/09 Full of Grace.flac Dec 26 18:38:33 volumio volumio[1002]: info: Exploding uri music-library/USB/music/Sarah McLachlan/Surfacing/10 Last Dance.flac in service mpd Dec 26 18:38:33 volumio volumio[1002]: info: ALBUMART /albumart?cacheid=63&web=Sarah%20McLachlan/Surfacing/extralarge&path=%2Fmnt%2FUSB%2Fmusic%2FSarah%20McLachlan%2FSurfacing%2F10%20Last%20Dance.flac&metadata=false Dec 26 18:38:33 volumio volumio[1002]: info: URI /mnt/USB/music/Sarah McLachlan/Surfacing/10 Last Dance.flac Dec 26 18:38:37 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:37 volumio volumio[1002]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::ClearQueue Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::clearPlayQueue Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::saveQueue Dec 26 18:38:37 volumio volumio[1002]: info: CoreCommandRouter::volumioPushQueue Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::addQueueItems Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::addQueueItems Dec 26 18:38:37 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:37 volumio volumio[1002]: info: CoreCommandRouter::volumioPushQueue Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::saveQueue Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:37 volumio volumio[1002]: info: CoreCommandRouter::volumioPlay Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::play index 0 Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::addQueueItems Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::addQueueItems Dec 26 18:38:37 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/04 Adia.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/04 Adia.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/05 Do What You Have to Do.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/05 Do What You Have to Do.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/06 Witness.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/06 Witness.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/07 Angel.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/07 Angel.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/08 Black & White.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/08 Black & White.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/09 Full of Grace.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/09 Full of Grace.flac Dec 26 18:38:37 volumio volumio[1002]: info: Adding Item to queue: music-library/USB/music/Sarah McLachlan/Surfacing/10 Last Dance.flac Dec 26 18:38:37 volumio volumio[1002]: info: Using cached record of: music-library/USB/music/Sarah McLachlan/Surfacing/10 Last Dance.flac Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:37 volumio volumio[1002]: info: CoreCommandRouter::volumioPushQueue Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::saveQueue Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::play index undefined Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:37 volumio volumio[1002]: info: CoreStateMachine::startPlaybackTimer Dec 26 18:38:37 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand stop took 2 milliseconds Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand clear took 0 milliseconds Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac" Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac" took 5 milliseconds Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 4ms Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 3ms Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: error: Upnp client error: Error: This socket has been ended by the other party Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 6ms Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 5ms Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand play took 4 milliseconds Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 4ms Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:37 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:37 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:37 volumio volumio[1002]: info: ------------------------------ 3ms Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:37 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:37 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand status took 19 milliseconds Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:37 volumio volumio[1002]: info: Dec 26 18:38:37 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:37 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:37 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand status took 20 milliseconds Dec 26 18:38:37 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: 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":null,"isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 32ms Dec 26 18:38:38 volumio volumio[1002]: info: Dec 26 18:38:38 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:38 volumio volumio[1002]: info: Dec 26 18:38:38 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand status took 17 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:38 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:38 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 138ms Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 139ms Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 102 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 26 18:38:38 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 101 milliseconds Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:38 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:38 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:38 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:38 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:38 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:38 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 230ms Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 221ms Dec 26 18:38:38 volumio volumio[1002]: info: ------------------------------ 220ms Dec 26 18:38:38 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 26 18:38:48 volumio kernel: hwmon hwmon1: Voltage normalised Dec 26 18:38:49 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:49 volumio volumio[1002]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::ClearQueue Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::stPlaybackTimer Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:49 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::serviceStop Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:49 volumio volumio[1002]: info: CoreCommandRouter::serviceStop Dec 26 18:38:49 volumio volumio[1002]: info: ControllerMpd::stop Dec 26 18:38:49 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::clearPlayQueue Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::saveQueue Dec 26 18:38:49 volumio volumio[1002]: info: CoreCommandRouter::volumioPushQueue Dec 26 18:38:49 volumio volumio[1002]: info: CoreStateMachine::addQueueItems Dec 26 18:38:49 volumio volumio[1002]: info: CorePlayQueue::addQueueItems Dec 26 18:38:49 volumio volumio[1002]: info: Preload queue cleared Dec 26 18:38:49 volumio volumio[1002]: info: Adding Item to queue: artists://Sarah%20McLachlan/Surfacing Dec 26 18:38:49 volumio volumio[1002]: info: Exploding uri artists://Sarah%20McLachlan/Surfacing in service mpd Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushQueue Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::saveQueue Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPlay Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::play index 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::play index undefined Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::startPlaybackTimer Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand stop took 30 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand stop took 2 milliseconds Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 5 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand clear took 5 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 2 milliseconds Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac" Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: 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} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: No code Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: 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} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: No code Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 61ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 61ms Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: error: updateQueue error: null Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 65ms Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 64 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac" took 64 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 6ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 5ms Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Dec 26 18:38:50 volumio volumio[1002]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 73ms Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:50 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 19ms Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand play took 16 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 15ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 14ms Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 7 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: Dec 26 18:38:50 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 15 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 13 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"479 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"706 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:50 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"680 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:50 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 51ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 105ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 103ms Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 91 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 89 milliseconds Dec 26 18:38:50 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:50 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:50 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":247,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"Building a Mystery","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/01 Building a Mystery.flac","trackType":"flac"} Dec 26 18:38:50 volumio volumio[1002]: verbose: CURRENT POSITION 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:50 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:50 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:50 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 216ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 210ms Dec 26 18:38:50 volumio volumio[1002]: info: ------------------------------ 209ms Dec 26 18:38:50 volumio kernel: hwmon hwmon1: Undervoltage detected! Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioNext Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::next Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::stPlaybackTimer Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::serviceStop Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 0 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::serviceStop Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::stop Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand stop took 16 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::play index undefined Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::startPlaybackTimer Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand stop took 3 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand clear took 8 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac" Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: 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} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: No code Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: 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} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: No code Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 116ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 116ms Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: error: updateQueue error: null Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 121ms Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 119 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac" took 124 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 13ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 11ms Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Dec 26 18:38:56 volumio volumio[1002]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 141ms Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:38:56 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 32ms Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand play took 23 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 20ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 18ms Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 8 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: Dec 26 18:38:56 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 17 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 16 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:56 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:56 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 66ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 125ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 124ms Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 112 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand status took 109 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 100 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:56 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 171ms Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 26 18:38:56 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 90 milliseconds Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"198 Kbps","isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:56 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"404 Kbps","isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:38:56 volumio volumio[1002]: verbose: CURRENT POSITION 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:38:56 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:38:56 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:38:56 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 277ms Dec 26 18:38:56 volumio volumio[1002]: info: ------------------------------ 275ms Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioNext Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::next Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::stop Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::stPlaybackTimer Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::serviceStop Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 1 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::serviceStop Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::stop Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand stop took 17 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::play index undefined Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::startPlaybackTimer Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::clearAddPlayTracks USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::updateTrackBlock Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrackBlock Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 9 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand stop took 6 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 3 milliseconds Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand clear Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand clear took 5 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac" Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Love You","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/02 I Love You.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService stop Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: No code Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 26 18:39:00 volumio volumio[1002]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 49ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 43ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 41ms Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: error: updateQueue error: null Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 44ms Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand add "USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac" took 41 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 6ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 5ms Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand play Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces system playlist update Dec 26 18:39:00 volumio volumio[1002]: info: Ignoring MPD Status Update Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 16ms Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand play took 13 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 12ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 11ms Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 25 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 24 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 24 milliseconds Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: Dec 26 18:39:00 volumio volumio[1002]: ---------------------------- MPD announces state update: player Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::getState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand status Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 10 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 6 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand status took 4 milliseconds Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseState Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus stop Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:39:00 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:39:00 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 76ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 148ms Dec 26 18:39:00 volumio volumio[1002]: info: ------------------------------ 147ms Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 116 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 116 milliseconds Dec 26 18:39:00 volumio volumio[1002]: info: sendMpdCommand playlistinfo took 116 milliseconds Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: verbose: ControllerMpd::parseTrackInfo Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"549 Kbps","isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:39:00 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"562 Kbps","isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:39:00 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: ControllerMpd::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::servicePushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":241,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"544 Kbps","isStreaming":false,"title":"Sweet Surrender","artist":"Sarah McLachlan","album":"Surfacing","uri":"USB/music/Sarah McLachlan/Surfacing/03 Sweet Surrender.flac","trackType":"flac"} Dec 26 18:39:00 volumio volumio[1002]: verbose: CURRENT POSITION 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState stateService play Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::syncState currentStatus play Dec 26 18:39:00 volumio volumio[1002]: info: Received an update from plugin. extracting info from payload Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:00 volumio volumio[1002]: info: CoreStateMachine::pushState Dec 26 18:39:00 volumio volumio[1002]: info: CorePlayQueue::getTrack 2 Dec 26 18:39:00 volumio volumio[1002]: info: CoreCommandRouter::volumioPushState Dec 26 18:39:01 volumio volumio[1002]: info: ------------------------------ 245ms Dec 26 18:39:01 volumio volumio[1002]: info: ------------------------------ 240ms Dec 26 18:39:01 volumio volumio[1002]: info: ------------------------------ 240ms Dec 26 18:39:03 volumio volumio[1002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 18:39:03 volumio volumio[1002]: Error: socket hang up Dec 26 18:39:03 volumio volumio[1002]: at connResetException (internal/errors.js:607:14) Dec 26 18:39:03 volumio volumio[1002]: at TLSSocket.socketOnEnd (_http_client.js:493:23) Dec 26 18:39:03 volumio volumio[1002]: at TLSSocket.emit (events.js:327:22) Dec 26 18:39:03 volumio volumio[1002]: at endReadableNT (internal/streams/readable.js:1327:12) Dec 26 18:39:03 volumio volumio[1002]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Dec 26 18:39:03 volumio volumio[1002]: code: 'ECONNRESET' Dec 26 18:39:03 volumio volumio[1002]: } Dec 26 18:39:03 volumio volumio[1002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 18:39:05 volumio sudo[8616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 18:38 Dec 26 18:39:05 volumio sudo[8616]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"