-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2026-01-11 10:01:04 CET. -- Jan 11 10:00:00 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jan 11 10:00:02 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jan 11 10:00:02 volumio systemd[1]: Started Cleanup of Temporary Directories. Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:02 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:02 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:02 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:02 volumio volumio[995]: info: Jan 11 10:00:02 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 17ms Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand status took 17 milliseconds Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 16ms Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand status took 15 milliseconds Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 14ms Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand status took 13 milliseconds Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:02 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 10:00:02 volumio volumio[995]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:02 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1805,"duration":236,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rêve orange","artist":"Liane Foly","album":"Au fur et à mesure... (Best of)","uri":"USB/TOSHIBA_EXT/music/ogg/Liane Foly/Au fur et à mesure... (Best of)/02.Rêve orange.ogg","trackType":"ogg"} Jan 11 10:00:02 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:02 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1805,"duration":236,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rêve orange","artist":"Liane Foly","album":"Au fur et à mesure... (Best of)","uri":"USB/TOSHIBA_EXT/music/ogg/Liane Foly/Au fur et à mesure... (Best of)/02.Rêve orange.ogg","trackType":"ogg"} Jan 11 10:00:02 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:02 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1805,"duration":236,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Rêve orange","artist":"Liane Foly","album":"Au fur et à mesure... (Best of)","uri":"USB/TOSHIBA_EXT/music/ogg/Liane Foly/Au fur et à mesure... (Best of)/02.Rêve orange.ogg","trackType":"ogg"} Jan 11 10:00:02 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:02 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:02 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:02 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 101ms Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 99ms Jan 11 10:00:02 volumio volumio[995]: info: ------------------------------ 98ms Jan 11 10:00:02 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:02 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:00:39 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:39 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:46 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 10:00:46 volumio volumio[995]: info: CURURI: artists://LP Jan 11 10:00:46 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:46 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:46 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:46 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/02.NO WITNESS.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/03.LOST ON YOU.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/04.UP AGAINST ME.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/05.TIGHTROPE.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/06.OTHER PEOPLE.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/07.INTO THE WILD.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/08.STRANGE.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/09.DEATH VALLEY.ogg Jan 11 10:00:46 volumio volumio[995]: info: Preloading song: music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/10.YOU WANT IT ALL.ogg Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/02.NO WITNESS.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/03.LOST ON YOU.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/05.TIGHTROPE.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/04.UP AGAINST ME.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/06.OTHER PEOPLE.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/07.INTO THE WILD.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/08.STRANGE.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/09.DEATH VALLEY.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: Exploding uri music-library/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/10.YOU WANT IT ALL.ogg in service mpd Jan 11 10:00:51 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 11 10:00:51 volumio volumio[995]: info: CURURI: artists://LP/Lost%20On%20You Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F01.MUDDY%20WATERS.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F02.NO%20WITNESS.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/02.NO WITNESS.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F03.LOST%20ON%20YOU.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/03.LOST ON YOU.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F05.TIGHTROPE.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/05.TIGHTROPE.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F04.UP%20AGAINST%20ME.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/04.UP AGAINST ME.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F06.OTHER%20PEOPLE.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/06.OTHER PEOPLE.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F07.INTO%20THE%20WILD.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/07.INTO THE WILD.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F08.STRANGE.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/08.STRANGE.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F09.DEATH%20VALLEY.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/09.DEATH VALLEY.ogg Jan 11 10:00:51 volumio volumio[995]: info: ALBUMART /albumart?cacheid=240&web=LP/Lost%20On%20You/extralarge&path=%2Fmnt%2FUSB%2FTOSHIBA_EXT%2Fmusic%2Fogg%2FLP%2FLost%20On%20You%2F10.YOU%20WANT%20IT%20ALL.ogg&metadata=false Jan 11 10:00:51 volumio volumio[995]: info: URI /mnt/USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/10.YOU WANT IT ALL.ogg Jan 11 10:00:51 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:51 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:51 volumio volumio[995]: info: No valid Plugin REST Endpoint Jan 11 10:00:51 volumio volumio[995]: info: No valid Plugin REST Endpoint Jan 11 10:00:55 volumio ntpd[786]: 94.142.246.192 local addr 192.168.1.53 -> Jan 11 10:00:58 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::ClearQueue Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::stop Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::serviceStop Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::serviceStop Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::stop Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::saveQueue Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::addQueueItems Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::addQueueItems Jan 11 10:00:58 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:58 volumio volumio[995]: info: Adding Item to queue: artists://LP/Lost%20On%20You Jan 11 10:00:58 volumio volumio[995]: info: Exploding uri artists://LP/Lost%20On%20You in service mpd Jan 11 10:00:58 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand stop took 28 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::saveQueue Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::play index 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::stop Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::play index undefined Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::clearAddPlayTracks USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 23 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand stop took 14 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 14 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 11 milliseconds Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand clear took 20 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand add "USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg" Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: 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":"Rêve orange","artist":"Liane Foly","album":"Au fur et à mesure... (Best of)","uri":"USB/TOSHIBA_EXT/music/ogg/Liane Foly/Au fur et à mesure... (Best of)/02.Rêve orange.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: No code Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 10:00:58 volumio volumio[995]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 72ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 61ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 60ms Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: error: updateQueue error: null Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 51ms Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand add "USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg" took 48 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 15ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 13ms Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand play Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:58 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 21ms Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand play took 19 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 20ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 18ms Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 16 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 15 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 15 milliseconds Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: Jan 11 10:00:58 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 17 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 6 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand status took 4 milliseconds Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 59ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 71ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 72ms Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 41 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 11 10:00:58 volumio volumio[995]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":777,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:58 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:58 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:58 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:58 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 111ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 99ms Jan 11 10:00:58 volumio volumio[995]: info: ------------------------------ 97ms Jan 11 10:00:59 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::ClearQueue Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::stPlaybackTimer Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::serviceStop Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::serviceStop Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::stop Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::clearPlayQueue Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::saveQueue Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::addQueueItems Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::addQueueItems Jan 11 10:00:59 volumio volumio[995]: info: Preload queue cleared Jan 11 10:00:59 volumio volumio[995]: info: Adding Item to queue: artists://LP/Lost%20On%20You Jan 11 10:00:59 volumio volumio[995]: info: Using cached record of: artists://LP/Lost%20On%20You Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushQueue Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::saveQueue Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::updateTrackBlock Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrackBlock Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPlay Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::play index 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::play index undefined Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::startPlaybackTimer Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::clearAddPlayTracks USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand stop took 22 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand stop took 5 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 5 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand clear took 5 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 4 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 4 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand add "USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg" Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: 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} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: No code Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: 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} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: No code Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 41ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 40ms Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: error: updateQueue error: null Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 48ms Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 47 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand add "USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg" took 47 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 10ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 10ms Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand play Jan 11 10:00:59 volumio volumio[995]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 58ms Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces system playlist update Jan 11 10:00:59 volumio volumio[995]: info: Ignoring MPD Status Update Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 11ms Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand play took 8 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 7ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 5ms Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 11 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 12 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 12 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 3 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: Jan 11 10:00:59 volumio volumio[995]: ---------------------------- MPD announces state update: player Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::getState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand status Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus stop Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:59 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:59 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:59 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 64ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 90ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 90ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 83ms Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 76 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand status took 75 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseState Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 10:00:59 volumio volumio[995]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: verbose: ControllerMpd::parseTrackInfo Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":502,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:59 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ControllerMpd::pushState Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::servicePushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":228,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MUDDY WATERS","artist":"LP","album":"Lost On You","uri":"USB/TOSHIBA_EXT/music/ogg/LP/Lost On You/01.MUDDY WATERS.ogg","trackType":"ogg"} Jan 11 10:00:59 volumio volumio[995]: verbose: CURRENT POSITION 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState stateService play Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::syncState currentStatus play Jan 11 10:00:59 volumio volumio[995]: info: Received an update from plugin. extracting info from payload Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: CoreStateMachine::pushState Jan 11 10:00:59 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:00:59 volumio volumio[995]: info: CoreCommandRouter::volumioPushState Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 132ms Jan 11 10:00:59 volumio volumio[995]: info: ------------------------------ 131ms Jan 11 10:01:03 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:01:03 volumio volumio[995]: Upnp client error: Error: This socket has been ended by the other party Jan 11 10:01:03 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 11 10:01:03 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jan 11 10:01:03 volumio volumio[995]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 11 10:01:04 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Jan 11 10:01:04 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:01:04 volumio volumio[995]: info: CoreCommandRouter::volumioGetState Jan 11 10:01:04 volumio volumio[995]: info: CorePlayQueue::getTrack 0 Jan 11 10:01:04 volumio volumio[995]: SPOTIFY: RECEIVED VOLUMIO VOLUME 48 Jan 11 10:01:04 volumio volumio[995]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 10:01:04 volumio volumio[995]: TypeError: Cannot read property 'slice' of null Jan 11 10:01:04 volumio volumio[995]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 11 10:01:04 volumio volumio[995]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 11 10:01:04 volumio volumio[995]: at Object.onceWrapper (events.js:422:26) Jan 11 10:01:04 volumio volumio[995]: at ClientRequest.emit (events.js:315:20) Jan 11 10:01:04 volumio volumio[995]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Jan 11 10:01:04 volumio volumio[995]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Jan 11 10:01:04 volumio volumio[995]: at TLSSocket.socketOnData (_http_client.js:509:22) Jan 11 10:01:04 volumio volumio[995]: at TLSSocket.emit (events.js:315:20) Jan 11 10:01:04 volumio volumio[995]: at addChunk (internal/streams/readable.js:309:12) Jan 11 10:01:04 volumio volumio[995]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 11 10:01:04 volumio volumio[995]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Jan 11 10:01:04 volumio volumio[995]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Jan 11 10:01:04 volumio volumio[995]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 10:01:04 volumio ntpd[786]: 162.159.200.123 local addr 192.168.1.53 -> Jan 11 10:01:04 volumio sudo[5135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-11 10:00 Jan 11 10:01:04 volumio sudo[5135]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"