-- Logs begin at Tue 2026-02-24 17:39:49 EET, end at Tue 2026-02-24 18:02:49 EET. -- Feb 24 18:01:02 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::ClearQueue Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 12 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 12 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::clearPlayQueue Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:02 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPlay Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::play index 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:02 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-08 Telling Stories.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-08 Telling Stories.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-09 Smoke And Ashes.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-09 Smoke And Ashes.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-10 Speak The Word.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-10 Speak The Word.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-11 Wedding Song.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-11 Wedding Song.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-12 Open Arms.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-12 Open Arms.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-13 Give Me One Reason.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-13 Give Me One Reason.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-14 Talkin' Bout A Revolution.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-14 Talkin' Bout A Revolution.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-15 She's Got Her Ticket.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-15 She's Got Her Ticket.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-16 All That You Have Is Your Soul.m4a Feb 24 18:01:02 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-16 All That You Have Is Your Soul.m4a Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand stop took 13 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand stop took 10 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a" Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: 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} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: No code Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: 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} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: No code Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a" took 7 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:02 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:02 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 65 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 65 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 64 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 66ms Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: Feb 24 18:01:02 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:02 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":182,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:02 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 70ms Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 70ms Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:02 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:02 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:02 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:01:02 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:02 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":299,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"804 Kbps","isStreaming":false,"title":"Fast Car","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a","trackType":"m4a"} Feb 24 18:01:02 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:02 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:02 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:02 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:02 volumio volumio[1334]: info: ------------------------------ 12ms Feb 24 18:01:05 volumio volumio[1334]: info: CoreCommandRouter::volumioAddQueueItems Feb 24 18:01:05 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:05 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:05 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:05 volumio volumio[1334]: info: Adding Item to queue: albums://Tracy%20Chapman/Collection Feb 24 18:01:05 volumio volumio[1334]: info: Exploding uri albums://Tracy%20Chapman/Collection in service mpd Feb 24 18:01:05 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:05 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:05 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:05 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::ClearQueue Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::clearPlayQueue Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:08 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-01 Fast Car.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPlay Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::play index 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:08 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-08 Telling Stories.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-08 Telling Stories.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-09 Smoke And Ashes.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-09 Smoke And Ashes.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-10 Speak The Word.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-10 Speak The Word.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-11 Wedding Song.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-11 Wedding Song.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-12 Open Arms.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-12 Open Arms.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-13 Give Me One Reason.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-13 Give Me One Reason.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-14 Talkin' Bout A Revolution.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-14 Talkin' Bout A Revolution.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-15 She's Got Her Ticket.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-15 She's Got Her Ticket.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Adding Item to queue: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-16 All That You Have Is Your Soul.m4a Feb 24 18:01:08 volumio volumio[1334]: info: Using cached record of: music-library/USB/Untitled/Music/Tracy Chapman/Collection/1-16 All That You Have Is Your Soul.m4a Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand stop took 13 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand stop took 8 milliseconds Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a" Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: 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} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: No code Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: 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} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: No code Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a" took 8 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:08 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:08 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 3ms Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 69 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 69 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 69 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 70ms Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: Feb 24 18:01:08 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:08 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:08 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:08 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:08 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:08 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:08 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 74ms Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 74ms Feb 24 18:01:08 volumio volumio[1334]: info: ------------------------------ 6ms Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 5 milliseconds Feb 24 18:01:08 volumio volumio[1334]: info: sendMpdCommand status took 5 milliseconds Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:08 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:09 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:01:09 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:01:09 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:09 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:09 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:09 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:09 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:09 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:09 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:09 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:09 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:09 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:09 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:09 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:09 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:13 volumio volumio[1334]: info: CoreCommandRouter::volumioAddQueueItems Feb 24 18:01:13 volumio volumio[1334]: info: CoreStateMachine::addQueueItems Feb 24 18:01:13 volumio volumio[1334]: info: CorePlayQueue::addQueueItems Feb 24 18:01:13 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:01:13 volumio volumio[1334]: info: Adding Item to queue: albums://Tracy%20Chapman/Collection Feb 24 18:01:13 volumio volumio[1334]: info: Using cached record of: albums://Tracy%20Chapman/Collection Feb 24 18:01:13 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:13 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:13 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:13 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPrevious Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::previous Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::seek Feb 24 18:01:22 volumio volumio[1334]: error: null Feb 24 18:01:22 volumio volumio[1334]: info: Feb 24 18:01:22 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:22 volumio volumio[1334]: info: Feb 24 18:01:22 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:22 volumio volumio[1334]: info: Feb 24 18:01:22 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:22 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:22 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:22 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:22 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: ------------------------------ 5ms Feb 24 18:01:22 volumio volumio[1334]: info: ------------------------------ 6ms Feb 24 18:01:22 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:22 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:22 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:22 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:22 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:22 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:22 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:22 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPrevious Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::previous Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: 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":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: No code Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: 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":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: No code Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: 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":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: No code Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand stop took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a" Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a" took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:24 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 60 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: Feb 24 18:01:24 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:24 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:24 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 65ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 66ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 65ms Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:24 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:24 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:24 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:24 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:24 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:24 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:24 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:24 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPrevious Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::previous Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::seek Feb 24 18:01:27 volumio volumio[1334]: error: null Feb 24 18:01:27 volumio volumio[1334]: info: Feb 24 18:01:27 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:27 volumio volumio[1334]: info: Feb 24 18:01:27 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:27 volumio volumio[1334]: info: Feb 24 18:01:27 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:27 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:27 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:27 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:27 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:27 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:27 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:01:27 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:27 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"870 Kbps","isStreaming":false,"title":"Subcity","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-02 Subcity.m4a","trackType":"m4a"} Feb 24 18:01:27 volumio volumio[1334]: verbose: CURRENT POSITION 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:27 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:27 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:27 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:27 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:28 volumio volumio[1334]: info: CoreCommandRouter::volumioNext Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::next Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:28 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:28 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrack 1 Feb 24 18:01:28 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand stop took 10 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:28 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:28 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a" Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:28 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 3ms Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a" took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:28 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:28 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:28 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:28 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:28 volumio volumio[1334]: info: Feb 24 18:01:28 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:28 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:28 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:29 volumio volumio[1334]: info: Feb 24 18:01:29 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 67 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 66 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 66 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: info: Feb 24 18:01:29 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:29 volumio volumio[1334]: info: Feb 24 18:01:29 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 69ms Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:29 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:29 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:29 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 73ms Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 74ms Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:01:29 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:29 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":195,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"815 Kbps","isStreaming":false,"title":"Baby Can I Hold You","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-03 Baby Can I Hold You.m4a","trackType":"m4a"} Feb 24 18:01:29 volumio volumio[1334]: verbose: CURRENT POSITION 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:29 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:29 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:29 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:29 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioNext Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::next Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 2 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand stop took 12 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a" Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a" took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:30 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: Feb 24 18:01:30 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:30 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:30 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 66ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 67ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 67ms Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand status took 4 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:30 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:30 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:30 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 24 18:01:30 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:30 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":328,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"676 Kbps","isStreaming":false,"title":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:30 volumio volumio[1334]: verbose: CURRENT POSITION 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:30 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:30 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:30 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:30 volumio volumio[1334]: info: ------------------------------ 18ms Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioNext Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::next Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 3 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a" Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: 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":"The Promise","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-04 The Promise.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: No code Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 5ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 5ms Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 4ms Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a" took 4 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:31 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 6ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 62 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 61 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":457,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:31 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:31 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 66ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 66ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 67ms Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:31 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:31 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"I'm Ready","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-05 I'm Ready.m4a","trackType":"m4a"} Feb 24 18:01:31 volumio volumio[1334]: verbose: CURRENT POSITION 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:31 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioNext Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::next Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 4 Feb 24 18:01:31 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:31 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a" Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:31 volumio volumio[1334]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 3ms Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a" took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:31 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 3ms Feb 24 18:01:31 volumio volumio[1334]: info: sendMpdCommand play took 1 milliseconds Feb 24 18:01:31 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:31 volumio volumio[1334]: info: Feb 24 18:01:31 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:31 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:31 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 65 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 67 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 67 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 69ms Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 2 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 72ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 72ms Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":253,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"828 Kbps","isStreaming":false,"title":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 11ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioNext Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::next Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 5 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::play index undefined Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::startPlaybackTimer Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::clearAddPlayTracks USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand stop took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand clear Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand clear took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a" Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: 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":"Crossroads","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-06 Crossroads.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: No code Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: 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} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: No code Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: 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} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: No code Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 8ms Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: error: updateQueue error: null Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 9ms Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand add "USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a" took 8 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 0ms Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand play Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces system playlist update Feb 24 18:01:32 volumio volumio[1334]: info: Ignoring MPD Status Update Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand play took 2 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 2ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 1ms Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 67 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 68 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 68 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 68ms Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: Feb 24 18:01:32 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 73ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 73ms Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:32 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":263,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"861 Kbps","isStreaming":false,"title":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:32 volumio volumio[1334]: verbose: CURRENT POSITION 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState stateService play Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus play Feb 24 18:01:32 volumio volumio[1334]: info: Received an update from plugin. extracting info from payload Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:32 volumio volumio[1334]: info: CorePlayQueue::getTrack 6 Feb 24 18:01:32 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 13ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 13ms Feb 24 18:01:32 volumio volumio[1334]: info: ------------------------------ 12ms Feb 24 18:01:41 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:41 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:41 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:41 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:41 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:43 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:43 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:43 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 3 Feb 24 18:01:43 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:43 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:43 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:43 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:43 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 2 Feb 24 18:01:43 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:43 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:44 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:44 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:44 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 1 Feb 24 18:01:44 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:44 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:44 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:44 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:44 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:44 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:44 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::stPlaybackTimer Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::updateTrackBlock Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrackBlock Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::serviceStop Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::serviceStop Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::stop Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand stop Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand stop took 14 milliseconds Feb 24 18:01:46 volumio volumio[1334]: info: Feb 24 18:01:46 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:46 volumio volumio[1334]: info: Feb 24 18:01:46 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:46 volumio volumio[1334]: info: Feb 24 18:01:46 volumio volumio[1334]: ---------------------------- MPD announces state update: player Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::getState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand status Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand status took 1 milliseconds Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand status took 0 milliseconds Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseState Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: 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":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:46 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: No code Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: 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":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:46 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: No code Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: ------------------------------ 6ms Feb 24 18:01:46 volumio volumio[1334]: info: ------------------------------ 7ms Feb 24 18:01:46 volumio volumio[1334]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 24 18:01:46 volumio volumio[1334]: verbose: ControllerMpd::parseTrackInfo Feb 24 18:01:46 volumio volumio[1334]: info: ControllerMpd::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::servicePushState Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: 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":"Bang Bang Bang","artist":"Tracy Chapman","album":"Collection","uri":"USB/Untitled/Music/Tracy Chapman/Collection/1-07 Bang Bang Bang.m4a","trackType":"m4a"} Feb 24 18:01:46 volumio volumio[1334]: verbose: CURRENT POSITION 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState stateService stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::syncState currentStatus stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: No code Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::pushState Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushState Feb 24 18:01:46 volumio volumio[1334]: info: ------------------------------ 10ms Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:46 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:46 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:46 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:47 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:47 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:47 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioRemoveQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::removeQueueItem Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::stop Feb 24 18:01:48 volumio volumio[1334]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::removeQueueItem 0 Feb 24 18:01:48 volumio volumio[1334]: info: CorePlayQueue::saveQueue Feb 24 18:01:48 volumio volumio[1334]: info: CoreCommandRouter::volumioPushQueue Feb 24 18:01:52 volumio volumio[1334]: info: CoreCommandRouter::volumioGetState Feb 24 18:01:52 volumio volumio[1334]: info: CorePlayQueue::getTrack 0 Feb 24 18:02:02 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:02 volumio volumio[1334]: info: CURURI: albums://Annie%20Lennox/Diva Feb 24 18:02:02 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/01 Why.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/02 Walking On Broken Glass.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/03 Precious.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/04 Legend In My Living Room.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/05 Cold.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/06 Money Can't Buy It.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/07 Little Bird.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/08 Primitive.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/09 Stay By Me.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/10 The Gift.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Diva/11 Keep Young And Beautiful.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/01 Why.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F01%20Why.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/01 Why.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/02 Walking On Broken Glass.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F02%20Walking%20On%20Broken%20Glass.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/02 Walking On Broken Glass.m4a Feb 24 18:02:02 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:02 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:02 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:02 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/03 Precious.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F03%20Precious.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/03 Precious.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/04 Legend In My Living Room.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F04%20Legend%20In%20My%20Living%20Room.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/04 Legend In My Living Room.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/05 Cold.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F05%20Cold.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/05 Cold.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/06 Money Can't Buy It.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F06%20Money%20Can't%20Buy%20It.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/06 Money Can't Buy It.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/07 Little Bird.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F07%20Little%20Bird.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/07 Little Bird.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/08 Primitive.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F08%20Primitive.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/08 Primitive.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/09 Stay By Me.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F09%20Stay%20By%20Me.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/09 Stay By Me.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/10 The Gift.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F10%20The%20Gift.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/10 The Gift.m4a Feb 24 18:02:02 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Diva/11 Keep Young And Beautiful.m4a in service mpd Feb 24 18:02:02 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Diva/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FDiva%2F11%20Keep%20Young%20And%20Beautiful.m4a&metadata=false Feb 24 18:02:02 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Diva/11 Keep Young And Beautiful.m4a Feb 24 18:02:11 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:11 volumio volumio[1334]: info: CURURI: albums:// Feb 24 18:02:11 volumio volumio[1334]: info: listAlbums - loading Albums from cache Feb 24 18:02:11 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:16 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:16 volumio volumio[1334]: info: CURURI: albums://Annie%20Lennox/Medusa Feb 24 18:02:16 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/01 No More _I Love You's_.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/02 Take Me to the River.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/03 A Whiter Shade of Pale.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/04 Don't Let It Bring You Down.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/05 Train in Vain.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/06 I Can't Get Next to You.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/07 Downtown Lights.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/08 Thin Line Between Love and Hate.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/09 Waiting in Vain.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Annie Lennox/Medusa/10 Something So Right.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/01 No More _I Love You's_.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F01%20No%20More%20_I%20Love%20You's_.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/01 No More _I Love You's_.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/02 Take Me to the River.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F02%20Take%20Me%20to%20the%20River.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/02 Take Me to the River.m4a Feb 24 18:02:16 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:16 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:16 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:16 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/03 A Whiter Shade of Pale.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F03%20A%20Whiter%20Shade%20of%20Pale.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/03 A Whiter Shade of Pale.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/04 Don't Let It Bring You Down.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F04%20Don't%20Let%20It%20Bring%20You%20Down.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/04 Don't Let It Bring You Down.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/05 Train in Vain.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F05%20Train%20in%20Vain.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/05 Train in Vain.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/06 I Can't Get Next to You.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F06%20I%20Can't%20Get%20Next%20to%20You.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/06 I Can't Get Next to You.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/07 Downtown Lights.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F07%20Downtown%20Lights.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/07 Downtown Lights.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/08 Thin Line Between Love and Hate.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F08%20Thin%20Line%20Between%20Love%20and%20Hate.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/08 Thin Line Between Love and Hate.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/09 Waiting in Vain.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F09%20Waiting%20in%20Vain.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/09 Waiting in Vain.m4a Feb 24 18:02:16 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Annie Lennox/Medusa/10 Something So Right.m4a in service mpd Feb 24 18:02:16 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=Annie%20Lennox/Medusa/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FAnnie%20Lennox%2FMedusa%2F10%20Something%20So%20Right.m4a&metadata=false Feb 24 18:02:16 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Annie Lennox/Medusa/10 Something So Right.m4a Feb 24 18:02:37 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:37 volumio volumio[1334]: info: CURURI: albums:// Feb 24 18:02:37 volumio volumio[1334]: info: listAlbums - loading Albums from cache Feb 24 18:02:37 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:39 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:39 volumio volumio[1334]: info: CURURI: artists:// Feb 24 18:02:39 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:47 volumio volumio[1334]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 24 18:02:47 volumio volumio[1334]: info: CURURI: artists://k.d.%20lang Feb 24 18:02:47 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:47 volumio volumio[1334]: info: Preload queue cleared Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/Compilations/The Unplugged Collection Volume One/05 Barefoot.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/01 Trail of Broken Hearts.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/02 Constant Craving.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/03 The Air That I Breathe.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/04 Helpless.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/05 You're OK.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/06 Western Stars.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/07 The Valley.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/08 Summerfling.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/09 Miss Chatelaine.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/10 I Dream of Spring.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/11 Hallellujah.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-01 Help Me.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-02 Hush Sweet Lover.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-03 Beautifully Combined.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-04 Crying WITH ROY ORBISON.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-05 Love For Sale.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-06 Golden Slumbers _ The End.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-07 Barefoot.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-08 Moonglow WITH TONY BENNETT.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-09 So In Love.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-10 Calling All Angels WITH JANE SIBERRY.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Preloading song: music-library/USB/Untitled/Music/k.d. lang/Recollection/2-11 Hallelujah NEW VERSION.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/Compilations/The Unplugged Collection Volume One/05 Barefoot.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/The%20Unplugged%20Collection%20Volume%20One/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2FCompilations%2FThe%20Unplugged%20Collection%20Volume%20One%2F05%20Barefoot.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/Compilations/The Unplugged Collection Volume One/05 Barefoot.m4a Feb 24 18:02:47 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:47 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:47 volumio volumio[1334]: info: No valid Plugin REST Endpoint Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/01 Trail of Broken Hearts.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F01%20Trail%20of%20Broken%20Hearts.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/01 Trail of Broken Hearts.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/02 Constant Craving.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F02%20Constant%20Craving.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/02 Constant Craving.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/03 The Air That I Breathe.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F03%20The%20Air%20That%20I%20Breathe.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/03 The Air That I Breathe.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/04 Helpless.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F04%20Helpless.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/04 Helpless.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/05 You're OK.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F05%20You're%20OK.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/05 You're OK.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/06 Western Stars.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F06%20Western%20Stars.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/06 Western Stars.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/07 The Valley.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F07%20The%20Valley.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/07 The Valley.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/08 Summerfling.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F08%20Summerfling.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/08 Summerfling.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/09 Miss Chatelaine.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F09%20Miss%20Chatelaine.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/09 Miss Chatelaine.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/10 I Dream of Spring.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F10%20I%20Dream%20of%20Spring.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/10 I Dream of Spring.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/11 Hallellujah.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F11%20Hallellujah.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/11 Hallellujah.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-01 Help Me.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-01%20Help%20Me.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-01 Help Me.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-02 Hush Sweet Lover.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-02%20Hush%20Sweet%20Lover.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-02 Hush Sweet Lover.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-03 Beautifully Combined.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-03%20Beautifully%20Combined.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-03 Beautifully Combined.m4a Feb 24 18:02:47 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-04 Crying WITH ROY ORBISON.m4a in service mpd Feb 24 18:02:47 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-04%20Crying%20WITH%20ROY%20ORBISON.m4a&metadata=false Feb 24 18:02:47 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-04 Crying WITH ROY ORBISON.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-05 Love For Sale.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-05%20Love%20For%20Sale.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-05 Love For Sale.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-06 Golden Slumbers _ The End.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-06%20Golden%20Slumbers%20_%20The%20End.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-06 Golden Slumbers _ The End.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-07 Barefoot.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-07%20Barefoot.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-07 Barefoot.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-08 Moonglow WITH TONY BENNETT.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-08%20Moonglow%20WITH%20TONY%20BENNETT.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-08 Moonglow WITH TONY BENNETT.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-09 So In Love.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-09%20So%20In%20Love.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-09 So In Love.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-10 Calling All Angels WITH JANE SIBERRY.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-10%20Calling%20All%20Angels%20WITH%20JANE%20SIBERRY.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-10 Calling All Angels WITH JANE SIBERRY.m4a Feb 24 18:02:48 volumio volumio[1334]: info: Exploding uri music-library/USB/Untitled/Music/k.d. lang/Recollection/2-11 Hallelujah NEW VERSION.m4a in service mpd Feb 24 18:02:48 volumio volumio[1334]: info: ALBUMART /albumart?cacheid=730&web=k.d.%20lang/Recollection/extralarge&path=%2Fmnt%2FUSB%2FUntitled%2FMusic%2Fk.d.%20lang%2FRecollection%2F2-11%20Hallelujah%20NEW%20VERSION.m4a&metadata=false Feb 24 18:02:48 volumio volumio[1334]: info: URI /mnt/USB/Untitled/Music/k.d. lang/Recollection/2-11 Hallelujah NEW VERSION.m4a Feb 24 18:02:49 volumio volumio[1334]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:02:49 volumio volumio[1334]: TypeError: Cannot read property 'slice' of null Feb 24 18:02:49 volumio volumio[1334]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Feb 24 18:02:49 volumio volumio[1334]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Feb 24 18:02:49 volumio volumio[1334]: at Object.onceWrapper (events.js:422:26) Feb 24 18:02:49 volumio volumio[1334]: at ClientRequest.emit (events.js:315:20) Feb 24 18:02:49 volumio volumio[1334]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:641:27) Feb 24 18:02:49 volumio volumio[1334]: at HTTPParser.parserOnHeadersComplete (_http_common.js:126:17) Feb 24 18:02:49 volumio volumio[1334]: at TLSSocket.socketOnData (_http_client.js:509:22) Feb 24 18:02:49 volumio volumio[1334]: at TLSSocket.emit (events.js:315:20) Feb 24 18:02:49 volumio volumio[1334]: at addChunk (internal/streams/readable.js:309:12) Feb 24 18:02:49 volumio volumio[1334]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 24 18:02:49 volumio volumio[1334]: at TLSSocket.Readable.push (internal/streams/readable.js:223:10) Feb 24 18:02:49 volumio volumio[1334]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Feb 24 18:02:49 volumio volumio[1334]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 18:02:49 volumio sudo[7053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-24 18:01 Feb 24 18:02:49 volumio sudo[7053]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"