-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Fri 2026-01-09 19:38:31 GMT. -- Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::play index undefined Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::resume Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand play took 17 milliseconds Jan 09 19:37:19 volumio volumio[1015]: info: Jan 09 19:37:19 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:19 volumio volumio[1015]: info: Jan 09 19:37:19 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:19 volumio volumio[1015]: info: Jan 09 19:37:19 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252217,"duration":262,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fly On A Windshield","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/02 Fly On A Windshield.mp3","trackType":"mp3"} Jan 09 19:37:19 volumio volumio[1015]: verbose: CURRENT POSITION 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus pause Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252217,"duration":262,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fly On A Windshield","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/02 Fly On A Windshield.mp3","trackType":"mp3"} Jan 09 19:37:19 volumio volumio[1015]: verbose: CURRENT POSITION 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:37:19 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:19 volumio volumio[1015]: info: ------------------------------ 27ms Jan 09 19:37:19 volumio volumio[1015]: info: ------------------------------ 26ms Jan 09 19:37:19 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 09 19:37:19 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:19 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252217,"duration":262,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Fly On A Windshield","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/02 Fly On A Windshield.mp3","trackType":"mp3"} Jan 09 19:37:19 volumio volumio[1015]: verbose: CURRENT POSITION 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:37:19 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:19 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:19 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:19 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:19 volumio volumio[1015]: info: ------------------------------ 42ms Jan 09 19:37:19 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:19 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:19 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:19 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:19 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:24 volumio volumio[1015]: info: CorePlayQueue::getTrack 2 Jan 09 19:37:24 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:24 volumio volumio[1015]: info: Prefetching next song Jan 09 19:37:24 volumio volumio[1015]: info: DOING PREFETCH IN MPD Jan 09 19:37:24 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/03 Broadway Melody Of 1974.mp3" Jan 09 19:37:24 volumio volumio[1015]: info: sendMpdCommand add "USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/03 Broadway Melody Of 1974.mp3" took 2 milliseconds Jan 09 19:37:24 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 09 19:37:24 volumio volumio[1015]: info: Jan 09 19:37:24 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:24 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:24 volumio volumio[1015]: info: sendMpdCommand consume 1 took 1 milliseconds Jan 09 19:37:24 volumio volumio[1015]: info: Jan 09 19:37:24 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:24 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:24 volumio volumio[1015]: info: Jan 09 19:37:24 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:24 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:24 volumio volumio[1015]: info: ------------------------------ 3ms Jan 09 19:37:24 volumio volumio[1015]: info: ------------------------------ 2ms Jan 09 19:37:25 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:37:25 volumio volumio[1015]: info: CURURI: artists:// Jan 09 19:37:25 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:37:28 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 09 19:37:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:28 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:28 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:28 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:37:28 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:28 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:29 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:29 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:29 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:29 volumio volumio[1015]: info: Jan 09 19:37:29 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 4ms Jan 09 19:37:29 volumio volumio[1015]: info: sendMpdCommand status took 4 milliseconds Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 3ms Jan 09 19:37:29 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 3ms Jan 09 19:37:29 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:37:29 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 19:37:29 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:29 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":33,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Broadway Melody Of 1974","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/03 Broadway Melody Of 1974.mp3","trackType":"mp3"} Jan 09 19:37:29 volumio volumio[1015]: verbose: CURRENT POSITION 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:37:29 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":33,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Broadway Melody Of 1974","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/03 Broadway Melody Of 1974.mp3","trackType":"mp3"} Jan 09 19:37:29 volumio volumio[1015]: verbose: CURRENT POSITION 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:37:29 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":33,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Broadway Melody Of 1974","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/03 Broadway Melody Of 1974.mp3","trackType":"mp3"} Jan 09 19:37:29 volumio volumio[1015]: verbose: CURRENT POSITION 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:37:29 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:37:29 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:29 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 42ms Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 41ms Jan 09 19:37:29 volumio volumio[1015]: info: ------------------------------ 41ms Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:29 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:37:42 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:37:42 volumio volumio[1015]: info: CURURI: artists://Genesis Jan 09 19:37:42 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:37:42 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:37:42 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:42 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:45 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:37:45 volumio volumio[1015]: info: CURURI: artists://Genesis/Genesis Jan 09 19:37:45 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:37:45 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:45 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:55 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:37:55 volumio volumio[1015]: info: CURURI: artists://Genesis/Nursery%20Cryme Jan 09 19:37:55 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/01 The Musical Box.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/02 For Absent Friends.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/03 The Return Of The Giant Hogweed.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/04 Seven Stones.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/05 Harold The Barrel.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/06 Harlequin.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/07 The Fountain Of Salmacis.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/01 The Musical Box.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F01%20The%20Musical%20Box.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/01 The Musical Box.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/02 For Absent Friends.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F02%20For%20Absent%20Friends.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/02 For Absent Friends.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/03 The Return Of The Giant Hogweed.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F03%20The%20Return%20Of%20The%20Giant%20Hogweed.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/03 The Return Of The Giant Hogweed.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/04 Seven Stones.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F04%20Seven%20Stones.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/04 Seven Stones.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/05 Harold The Barrel.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F05%20Harold%20The%20Barrel.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/05 Harold The Barrel.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/06 Harlequin.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F06%20Harlequin.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/06 Harlequin.mp3 Jan 09 19:37:55 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Nursery Cryme/07 The Fountain Of Salmacis.mp3 in service mpd Jan 09 19:37:55 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Nursery%20Cryme/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FNursery%20Cryme%2F07%20The%20Fountain%20Of%20Salmacis.mp3&metadata=false Jan 09 19:37:55 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Nursery Cryme/07 The Fountain Of Salmacis.mp3 Jan 09 19:37:57 volumio volumio[1015]: info: CorePlayQueue::getTrack 3 Jan 09 19:37:57 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:37:57 volumio volumio[1015]: info: Prefetching next song Jan 09 19:37:57 volumio volumio[1015]: info: DOING PREFETCH IN MPD Jan 09 19:37:57 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/04 Cuckoo Cocoon.mp3" Jan 09 19:37:57 volumio volumio[1015]: info: sendMpdCommand add "USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/04 Cuckoo Cocoon.mp3" took 1 milliseconds Jan 09 19:37:57 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 09 19:37:57 volumio volumio[1015]: info: Jan 09 19:37:57 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:57 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:57 volumio volumio[1015]: info: sendMpdCommand consume 1 took 1 milliseconds Jan 09 19:37:57 volumio volumio[1015]: info: Jan 09 19:37:57 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:57 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:57 volumio volumio[1015]: info: Jan 09 19:37:57 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:37:57 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:37:57 volumio volumio[1015]: info: ------------------------------ 2ms Jan 09 19:37:57 volumio volumio[1015]: info: ------------------------------ 2ms Jan 09 19:37:57 volumio volumio[1015]: info: ------------------------------ 1ms Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:02 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:02 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:02 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:02 volumio volumio[1015]: info: Jan 09 19:38:02 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 4ms Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 2ms Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 3ms Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":131,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cuckoo Cocoon","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/04 Cuckoo Cocoon.mp3","trackType":"mp3"} Jan 09 19:38:02 volumio volumio[1015]: verbose: CURRENT POSITION 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:02 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":131,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cuckoo Cocoon","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/04 Cuckoo Cocoon.mp3","trackType":"mp3"} Jan 09 19:38:02 volumio volumio[1015]: verbose: CURRENT POSITION 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:02 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 30ms Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 29ms Jan 09 19:38:02 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 09 19:38:02 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:02 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":131,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Cuckoo Cocoon","artist":"Genesis","album":"The Lamb Lies Down On Broadway","uri":"USB/Elements/iTunes Music/Genesis/The Lamb Lies Down On Broadway/04 Cuckoo Cocoon.mp3","trackType":"mp3"} Jan 09 19:38:02 volumio volumio[1015]: verbose: CURRENT POSITION 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:02 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:02 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:02 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:02 volumio volumio[1015]: info: ------------------------------ 49ms Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:02 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:03 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:38:03 volumio volumio[1015]: info: CURURI: artists://Genesis/Selling%20England%20By%20The%20Pound Jan 09 19:38:03 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/01 Dancing With The Moonlit Knight.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/03 Firth Of Fifth.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/04 More Fool Me.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/06 After The Ordeal.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/07 The Cinema Show.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Preloading song: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/08 Aisle Of Plenty.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/01 Dancing With The Moonlit Knight.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F01%20Dancing%20With%20The%20Moonlit%20Knight.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/01 Dancing With The Moonlit Knight.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: No valid Plugin REST Endpoint Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F02%20I%20Know%20What%20I%20Like%20(In%20Your%20Wardrobe).mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/03 Firth Of Fifth.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F03%20Firth%20Of%20Fifth.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/03 Firth Of Fifth.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/04 More Fool Me.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F04%20More%20Fool%20Me.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/04 More Fool Me.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F05%20The%20Battle%20Of%20Epping%20Forest.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/06 After The Ordeal.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F06%20After%20The%20Ordeal.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/06 After The Ordeal.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/07 The Cinema Show.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F07%20The%20Cinema%20Show.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/07 The Cinema Show.mp3 Jan 09 19:38:03 volumio volumio[1015]: info: Exploding uri music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/08 Aisle Of Plenty.mp3 in service mpd Jan 09 19:38:03 volumio volumio[1015]: info: ALBUMART /albumart?cacheid=727&web=Genesis/Selling%20England%20By%20The%20Pound/extralarge&path=%2Fmnt%2FUSB%2FElements%2FiTunes%20Music%2FGenesis%2FSelling%20England%20By%20The%20Pound%2F08%20Aisle%20Of%20Plenty.mp3&metadata=false Jan 09 19:38:03 volumio volumio[1015]: info: URI /mnt/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/08 Aisle Of Plenty.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::ClearQueue Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::stPlaybackTimer Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::serviceStop Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 4 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::serviceStop Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::stop Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::clearPlayQueue Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::saveQueue Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Jan 09 19:38:07 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/01 Dancing With The Moonlit Knight.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/01 Dancing With The Moonlit Knight.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::saveQueue Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPlay Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::play index 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::addQueueItems Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::addQueueItems Jan 09 19:38:07 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/03 Firth Of Fifth.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/03 Firth Of Fifth.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/04 More Fool Me.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/04 More Fool Me.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/05 The Battle Of Epping Forest.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/06 After The Ordeal.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/06 After The Ordeal.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/07 The Cinema Show.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/07 The Cinema Show.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Adding Item to queue: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/08 Aisle Of Plenty.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: Using cached record of: music-library/USB/Elements/iTunes Music/Genesis/Selling England By The Pound/08 Aisle Of Plenty.mp3 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushQueue Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::saveQueue Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::play index undefined Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::updateTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrackBlock Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::startPlaybackTimer Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::clearAddPlayTracks USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3 Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand stop Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand stop took 34 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand stop took 5 milliseconds Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand clear Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 3 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand clear took 3 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand add "USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3" Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: No code Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: No code Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 55ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 55ms Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: error: updateQueue error: null Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 58ms Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 57 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand add "USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3" took 57 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 3ms Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand play Jan 09 19:38:07 volumio volumio[1015]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 63ms Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces system playlist update Jan 09 19:38:07 volumio volumio[1015]: info: Ignoring MPD Status Update Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 16ms Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand play took 14 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 13ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 13ms Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 4 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 1 milliseconds Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: Jan 09 19:38:07 volumio volumio[1015]: ---------------------------- MPD announces state update: player Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::getState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand status Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 4 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand status took 2 milliseconds Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseState Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus stop Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:07 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:07 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 22ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 33ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 34ms Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 29 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 09 19:38:07 volumio volumio[1015]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: verbose: ControllerMpd::parseTrackInfo Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:07 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:07 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ControllerMpd::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::servicePushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":248,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"I Know What I Like (In Your Wardrobe)","artist":"Genesis","album":"Selling England By The Pound","uri":"USB/Elements/iTunes Music/Genesis/Selling England By The Pound/02 I Know What I Like (In Your Wardrobe).mp3","trackType":"mp3"} Jan 09 19:38:07 volumio volumio[1015]: verbose: CURRENT POSITION 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState stateService play Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::syncState currentStatus play Jan 09 19:38:07 volumio volumio[1015]: info: Received an update from plugin. extracting info from payload Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: CoreStateMachine::pushState Jan 09 19:38:07 volumio volumio[1015]: info: CorePlayQueue::getTrack 1 Jan 09 19:38:07 volumio volumio[1015]: info: CoreCommandRouter::volumioPushState Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 49ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 47ms Jan 09 19:38:07 volumio volumio[1015]: info: ------------------------------ 46ms Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:07 volumio volumio[1015]: SPOTIFY: RECEIVED VOLUMIO VOLUME 62 Jan 09 19:38:13 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:38:13 volumio volumio[1015]: info: CURURI: music-library Jan 09 19:38:13 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:17 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:38:17 volumio volumio[1015]: info: CURURI: albums:// Jan 09 19:38:17 volumio volumio[1015]: info: listAlbums - loading Albums from cache Jan 09 19:38:17 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:19 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 09 19:38:19 volumio volumio[1015]: info: CURURI: artists:// Jan 09 19:38:19 volumio volumio[1015]: info: Preload queue cleared Jan 09 19:38:25 volumio volumio[1015]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Old%20Man%20Gloom/e82089de-d0b7-4d07-bdbd-cb7d08b56d08.jpg' Jan 09 19:38:27 volumio volumio[1015]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Quest%20For%20Fire/a3135b99-4ddb-4fdb-9150-555efa6a9991.jpg' Jan 09 19:38:28 volumio volumio[1015]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sod%20Hauler/2d3981ca-9ae4-4122-aee1-e9b74b1ca510.jpg' Jan 09 19:38:29 volumio volumio[1015]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Terra%20Firma/4cb67d55-6a07-44a6-a78d-8781cdf83014.jpg' Jan 09 19:38:29 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 19:38:29 volumio volumio[1015]: TypeError: Cannot read property 'slice' of null Jan 09 19:38:29 volumio volumio[1015]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jan 09 19:38:29 volumio volumio[1015]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jan 09 19:38:29 volumio volumio[1015]: at Object.onceWrapper (events.js:422:26) Jan 09 19:38:29 volumio volumio[1015]: at ClientRequest.emit (events.js:315:20) Jan 09 19:38:29 volumio volumio[1015]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Jan 09 19:38:29 volumio volumio[1015]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Jan 09 19:38:29 volumio volumio[1015]: at TLSSocket.socketOnData (_http_client.js:509:22) Jan 09 19:38:29 volumio volumio[1015]: at TLSSocket.emit (events.js:315:20) Jan 09 19:38:29 volumio volumio[1015]: at addChunk (internal/streams/readable.js:309:12) Jan 09 19:38:29 volumio volumio[1015]: at readableAddChunk (internal/streams/readable.js:284:9) Jan 09 19:38:29 volumio volumio[1015]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Jan 09 19:38:29 volumio volumio[1015]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Jan 09 19:38:29 volumio volumio[1015]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 09 19:38:31 volumio sudo[11104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-09 19:37 Jan 09 19:38:31 volumio sudo[11104]: 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"