-- Logs begin at Tue 2026-02-10 15:26:40 CET, end at Tue 2026-02-10 16:57:06 CET. -- Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 10 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::ClearQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 10 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 10 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::clearPlayQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::addQueueItems Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::addQueueItems Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Adding Item to queue: albums://Buddy%20Tate/Body%20And%20Soul Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Exploding uri albums://Buddy%20Tate/Body%20And%20Soul in service mpd Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPlay Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::play index 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 23 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac" Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: 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 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: 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 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac" took 7 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:03 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand play took 4 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 3 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 4 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Feb 10 16:56:03 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":509,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6 Kbps","isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":619,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6 Kbps","isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":648,"duration":432,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"6 Kbps","isStreaming":false,"title":"Stompin' At The Savoy","artist":"Buddy Tate","album":"Body And Soul","uri":"NAS/Buddy Tate - Body And Soul (2018) [24-192] 1975/01. Stompin' At The Savoy.flac","trackType":"flac"} Feb 10 16:56:03 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 13ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:03 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:56:03 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:56:08 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:08 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::ClearQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::clearPlayQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::addQueueItems Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::addQueueItems Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Adding Item to queue: albums://Candi%20Staton/Candi%20Staton Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Exploding uri albums://Candi%20Staton/Candi%20Staton in service mpd Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPlay Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::play index 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 23 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac" Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: 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 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: 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 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 8 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac" took 8 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 0ms Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:09 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand play took 4 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 3 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 3 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 3 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Feb 10 16:56:09 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":190,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"516 Kbps","isStreaming":false,"title":"I'm Just a Prisoner (Of Your Good Lovin')","artist":"Candi Staton","album":"Candi Staton","uri":"NAS/Candi Staton/Candi Staton (Candi Staton) 2004 Flac/01 I'm Just a Prisoner (Of Your Good Lovin') .flac","trackType":"flac"} Feb 10 16:56:09 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:09 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 13ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:09 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:13 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:13 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:14 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:14 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:19 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:19 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::ClearQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::clearPlayQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::addQueueItems Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::addQueueItems Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Preload queue cleared Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Adding Item to queue: albums://Carl%20Carlton/Carl%20Carlton Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Exploding uri albums://Carl%20Carlton/Carl%20Carlton in service mpd Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::saveQueue Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPlay Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::play index 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 23 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac" Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: 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 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: 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 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac" took 9 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 0ms Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:21 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand play took 3 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Feb 10 16:56:21 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"101 Kbps","isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":366,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"856 Kbps","isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":222,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"856 Kbps","isStreaming":false,"title":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:21 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:21 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 15ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:21 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:23 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:23 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:23 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:56:23 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioNext Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::next Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 0 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 4 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac" Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: 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":"Sexy Lady","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/01. Sexy Lady.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 6ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 6ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac" took 5 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand play took 2 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 2ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Feb 10 16:56:24 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":233,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:24 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:24 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioNext Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::next Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 1 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 3 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac" Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: 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":"Let Me Love You 'Til The Morning Comes","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/02. Let Me Love You 'Til The Morning Comes.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 6ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 5ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 5ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac" took 5 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:25 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand play took 3 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 2ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 0ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 0ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Feb 10 16:56:25 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 11ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":243,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:25 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:25 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 13ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:25 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioNext Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::next Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 2 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 3 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 0 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac" Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: 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":"Don't You Wanna Make Love","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/03. Don't You Wanna Make Love.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: 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 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 8 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac" took 8 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: 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 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 12ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand play took 8 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 2 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 4ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 5ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 4 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 4 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"This Feeling's Rated X-Tra","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/04. This Feeling's Rated X-Tra.flac","trackType":"flac"} Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 18ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 18ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioNext Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::next Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::stPlaybackTimer Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::serviceStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 3 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::serviceStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::stop Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicStop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicStop *** Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 Off (musicStop) Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 3 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::play index undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::startPlaybackTimer Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::clearAddPlayTracks NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::updateTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrackBlock Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand stop took 0 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand clear Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand clear took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac" Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: error: updateQueue error: null Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand add "NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac" took 1 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand play Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: 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 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: 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 10 16:56:26 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: No code Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:26 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:26 progressivevolumio volumio[14181]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 10ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 9ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: sendMpdCommand play took 9 milliseconds Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 1ms Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Feb 10 16:56:26 progressivevolumio volumio[14181]: ---------------------------- MPD announces system playlist update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: Ignoring MPD Status Update Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: stop Feb 10 16:56:26 progressivevolumio volumio[14181]: info: ------------------------------ 3ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Feb 10 16:56:27 progressivevolumio volumio[14181]: ---------------------------- MPD announces state update: player Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::getState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand status Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 0 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand status took 1 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseState Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus stop Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 7ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 8ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: ControllerMpd::parseTrackInfo Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ControllerMpd::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::servicePushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":350,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"She's A Bad Mama Jama (She's Built, She's Stacked)","artist":"Carl Carlton","album":"Carl Carlton","uri":"NAS/Carl Carlton - Carl Carlton (1981) [FLAC]/05. She's A Bad Mama Jama (She's Built, She's Stacked).flac","trackType":"flac"} Feb 10 16:56:27 progressivevolumio volumio[14181]: verbose: CURRENT POSITION 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState stateService play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::syncState currentStatus play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: Received an update from plugin. extracting info from payload Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreStateMachine::pushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:27 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioPushState Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 15ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 15ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: ------------------------------ 14ms Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Handling event: musicPlay Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] *** musicPlay *** Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Turning GPIO 27 On (musicPlay) Feb 10 16:56:27 progressivevolumio volumio[14181]: info: [GPIO_Control] Status changed: play Feb 10 16:56:30 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:30 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:33 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:33 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:35 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:35 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:40 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:40 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:43 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:43 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:43 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:56:45 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:45 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:50 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:50 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:53 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:53 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:56:55 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:56:55 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:57:00 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:57:00 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:57:03 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:57:03 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:57:03 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:57:03 progressivevolumio volumio[14181]: info: Listing playlists Feb 10 16:57:05 progressivevolumio volumio[14181]: info: CoreCommandRouter::volumioGetState Feb 10 16:57:05 progressivevolumio volumio[14181]: info: CorePlayQueue::getTrack 4 Feb 10 16:57:06 progressivevolumio volumio[14181]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 16:57:06 progressivevolumio volumio[14181]: Error: connect ETIMEDOUT 151.101.66.79:443 Feb 10 16:57:06 progressivevolumio volumio[14181]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 10 16:57:06 progressivevolumio volumio[14181]: errno: -110, Feb 10 16:57:06 progressivevolumio volumio[14181]: code: 'ETIMEDOUT', Feb 10 16:57:06 progressivevolumio volumio[14181]: syscall: 'connect', Feb 10 16:57:06 progressivevolumio volumio[14181]: address: '151.101.66.79', Feb 10 16:57:06 progressivevolumio volumio[14181]: port: 443 Feb 10 16:57:06 progressivevolumio volumio[14181]: } Feb 10 16:57:06 progressivevolumio volumio[14181]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 16:57:06 progressivevolumio sudo[29161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-10 16:56 Feb 10 16:57:06 progressivevolumio sudo[29161]: 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"