-- Logs begin at Sat 2024-04-06 15:04:59 CEST, end at Mon 2024-04-08 21:58:19 CEST. --
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::play index 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 53 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 48 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 35 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 22 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 14 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 44 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand clear took 44 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 43 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3"
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: 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":"Track 10","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/10 - Unknown Artist - Track 10.mp3","trackType":"mp3"}
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: No code
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:07 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:07 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 259ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 215ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 205ms
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 205ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3" took 189 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 66ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 64ms
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:07 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:07 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 137ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand play took 117 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 114ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info: ------------------------------ 108ms
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 52 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 53 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand status took 32 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info:
Apr 08 21:57:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 136 milliseconds
Apr 08 21:57:07 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 138 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 134 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 128 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand status took 87 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand status took 76 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 462ms
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 601ms
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 584ms
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 578ms
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 362 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 365 milliseconds
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:08 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 730ms
Apr 08 21:57:08 volumio20 volumio[1130]: info: ------------------------------ 728ms
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:09 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","service":"mpd","name":"9 - Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Unknown%20Artist/Pocity%20s%20hudbou/extralarge&path=%2FINTERNAL%2FRelax%2FVA%20-%20Pocity%20s%20hudbou&metadata=false","duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:09 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:09 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:09 volumio20 volumio[1130]: error: null
Apr 08 21:57:09 volumio20 volumio[1130]: info:
Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:09 volumio20 volumio[1130]: info:
Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:09 volumio20 volumio[1130]: info:
Apr 08 21:57:09 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:09 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 65 milliseconds
Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 57 milliseconds
Apr 08 21:57:09 volumio20 volumio[1130]: info: sendMpdCommand status took 51 milliseconds
Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:09 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 31 milliseconds
Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 17 milliseconds
Apr 08 21:57:10 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 16 milliseconds
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":133000,"duration":241,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:10 volumio20 volumio[1130]: verbose: CURRENT POSITION 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:10 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:10 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 539ms
Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 538ms
Apr 08 21:57:10 volumio20 volumio[1130]: info: ------------------------------ 533ms
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::play index 7
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 8
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:14 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:14 volumio20 volumio[1130]: info:
Apr 08 21:57:14 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:14 volumio20 volumio[1130]: info: sendMpdCommand stop took 41 milliseconds
Apr 08 21:57:14 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:14 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:14 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3
Apr 08 21:57:14 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand stop took 59 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 23 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 16 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 47 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand clear took 48 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 46 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 44 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3"
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: 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":"Track 9","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/09 - Unknown Artist - Track 9.mp3","trackType":"mp3"}
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: No code
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:15 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 269ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 214ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 205ms
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 198ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3" took 182 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 61ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 54ms
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:15 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:15 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 169ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand play took 144 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 140ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 126ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 45 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 37 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info:
Apr 08 21:57:15 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 102 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 87 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 76 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 76 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 100 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: info: sendMpdCommand status took 88 milliseconds
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:15 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:15 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:15 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:15 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 431ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 496ms
Apr 08 21:57:15 volumio20 volumio[1130]: info: ------------------------------ 498ms
Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 367 milliseconds
Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 358 milliseconds
Apr 08 21:57:16 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 359 milliseconds
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":845,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:16 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 930ms
Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 878ms
Apr 08 21:57:16 volumio20 volumio[1130]: info: ------------------------------ 866ms
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","service":"mpd","name":"8 - Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Unknown%20Artist/Pocity%20s%20hudbou/extralarge&path=%2FINTERNAL%2FRelax%2FVA%20-%20Pocity%20s%20hudbou&metadata=false","duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:16 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:16 volumio20 volumio[1130]: error: null
Apr 08 21:57:16 volumio20 volumio[1130]: info:
Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:16 volumio20 volumio[1130]: info:
Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:16 volumio20 volumio[1130]: info:
Apr 08 21:57:16 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:16 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:16 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 82 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 67 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand status took 64 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 163 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 162 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 163 milliseconds
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":154000,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:17 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:17 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:17 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 654ms
Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 645ms
Apr 08 21:57:17 volumio20 volumio[1130]: info: ------------------------------ 643ms
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioClearQueue
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue
Apr 08 21:57:21 volumio20 volumio[1130]: info:
Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand stop took 100 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:21 volumio20 volumio[1130]: info:
Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:21 volumio20 volumio[1130]: info:
Apr 08 21:57:21 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 21 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand status took 11 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 62 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 63 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 65 milliseconds
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: No code
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: No code
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: 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":"Track 8","artist":"Unknown Artist","album":"Pocity s hudbou","uri":"INTERNAL/Relax/VA - Pocity s hudbou/08 - Unknown Artist - Track 8.mp3","trackType":"mp3"}
Apr 08 21:57:21 volumio20 volumio[1130]: verbose: CURRENT POSITION 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: No code
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 7
Apr 08 21:57:21 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 443ms
Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 425ms
Apr 08 21:57:21 volumio20 volumio[1130]: info: ------------------------------ 419ms
Apr 08 21:57:32 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 08 21:57:32 volumio20 volumio[1130]: info: CURURI: music-library/INTERNAL/Meditácia
Apr 08 21:57:32 volumio20 volumio[1130]: info: Preload queue cleared
Apr 08 21:57:32 volumio20 volumio[1130]: info: Preloading song: music-library/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3
Apr 08 21:57:32 volumio20 volumio[1130]: info: Preloading song: music-library/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3
Apr 08 21:57:32 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3 in service mpd
Apr 08 21:57:32 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBest%20Meditation%20Music%20..%20Oliver%20Shanti%20Vol.%20I.mp3&metadata=false
Apr 08 21:57:32 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Best Meditation Music .. Oliver Shanti Vol. I.mp3
Apr 08 21:57:32 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3 in service mpd
Apr 08 21:57:32 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=//extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FRELAX-%20Meditation%20and%20Sleep%20Music.mp3&metadata=false
Apr 08 21:57:32 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/RELAX- Meditation and Sleep Music.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::addQueueItems
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::addQueueItems
Apr 08 21:57:39 volumio20 volumio[1130]: info: Preload queue cleared
Apr 08 21:57:39 volumio20 volumio[1130]: info: Adding Item to queue: music-library/INTERNAL/Meditácia/Celtic Meditation Music
Apr 08 21:57:39 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Celtic Meditation Music in service mpd
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F01%20-%20Blind%20Mary.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F02%20-%20Aran%20Boat%20Song.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F03%20-%20Far%20Beyond%20Yon%20Mountain.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F04%20-%20Were%20You%20At%20The%20Rock.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F05%20-%20At%20The%20Edge%20Of%20The%20White%20Rock.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/05 - At The Edge Of The White Rock.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F06%20-%20Dark%20Island.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/06 - Dark Island.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F07%20-%20An%20Bhoutals.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/07 - An Bhoutals.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F08%20-%20Gentle%20Maiden.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/08 - Gentle Maiden.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F09%20-%20Dawning%20Of%20The%20Day.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/09 - Dawning Of The Day.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F10%20-%20Easter%20Snow.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music%2F11%20-%20Limericks%20Lamentation.mp3&metadata=false
Apr 08 21:57:39 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Celtic Meditation Music/11 - Limericks Lamentation.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::play index 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand stop took 8 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand clear took 26 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3"
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 46ms
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3" took 40 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 35ms
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 30ms
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:39 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 86ms
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand play took 72 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 70ms
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 51ms
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 100 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 98 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 85 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:39 volumio20 volumio[1130]: info:
Apr 08 21:57:39 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 138 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 66 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 64 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 71 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: info: sendMpdCommand status took 54 milliseconds
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:39 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:39 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:39 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:39 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:39 volumio20 volumio[1130]: info: ------------------------------ 448ms
Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 547ms
Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 521ms
Apr 08 21:57:40 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 334 milliseconds
Apr 08 21:57:40 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 327 milliseconds
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:40 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:40 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:40 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:40 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:40 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 846ms
Apr 08 21:57:40 volumio20 volumio[1130]: info: ------------------------------ 739ms
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","service":"mpd","name":"1 - Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: error: null
Apr 08 21:57:43 volumio20 volumio[1130]: info:
Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:43 volumio20 volumio[1130]: info:
Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:43 volumio20 volumio[1130]: info:
Apr 08 21:57:43 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 50 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand status took 30 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 39 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 42 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 41 milliseconds
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":67000,"duration":182,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:43 volumio20 volumio[1130]: verbose: CURRENT POSITION 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:43 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:43 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:43 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 785ms
Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 797ms
Apr 08 21:57:44 volumio20 volumio[1130]: info: ------------------------------ 797ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::next
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 0
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand stop took 70 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 63 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand stop took 49 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 35 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 24 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand clear took 23 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 22 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 21 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3"
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: 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":"Blind Mary","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/01 - Blind Mary.mp3","trackType":"mp3"}
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: No code
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:46 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:46 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:46 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:46 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 223ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 190ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 183ms
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 162ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3" took 149 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 47ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 41ms
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:46 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:46 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 138ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand play took 119 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 116ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info: ------------------------------ 107ms
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info:
Apr 08 21:57:46 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:46 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:46 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 52 milliseconds
Apr 08 21:57:46 volumio20 volumio[1130]: info: sendMpdCommand status took 61 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 68 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: info:
Apr 08 21:57:47 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:47 volumio20 volumio[1130]: info:
Apr 08 21:57:47 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 96 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 101 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 97 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 96 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 81 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand status took 73 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 512ms
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 765ms
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 744ms
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 739ms
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 543 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 543 milliseconds
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:47 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:47 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:47 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 947ms
Apr 08 21:57:47 volumio20 volumio[1130]: info: ------------------------------ 945ms
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","service":"mpd","name":"2 - Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: error: null
Apr 08 21:57:48 volumio20 volumio[1130]: info:
Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:48 volumio20 volumio[1130]: info:
Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:48 volumio20 volumio[1130]: info:
Apr 08 21:57:48 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 124 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 63 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand status took 49 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 145 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 145 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 141 milliseconds
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":164000,"duration":299,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:48 volumio20 volumio[1130]: verbose: CURRENT POSITION 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:48 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:48 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:48 volumio20 volumio[1130]: info: ------------------------------ 679ms
Apr 08 21:57:49 volumio20 volumio[1130]: info: ------------------------------ 616ms
Apr 08 21:57:49 volumio20 volumio[1130]: info: ------------------------------ 608ms
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::next
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 1
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand stop took 40 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand stop took 44 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 33 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand status took 26 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 34 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand clear took 34 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 32 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3"
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:52 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: 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":"Aran Boat Song","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/02 - Aran Boat Song.mp3","trackType":"mp3"}
Apr 08 21:57:52 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: No code
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:52 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:52 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:52 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:52 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 222ms
Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 190ms
Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 182ms
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:52 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:52 volumio20 volumio[1130]: info:
Apr 08 21:57:52 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:52 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 173ms
Apr 08 21:57:52 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3" took 170 milliseconds
Apr 08 21:57:52 volumio20 volumio[1130]: info: ------------------------------ 68ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 63ms
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:53 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:53 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:53 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 131ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand play took 106 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 103ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 83ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 45 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 37 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 76 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 69 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 57 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 50 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 278ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 299ms
Apr 08 21:57:53 volumio20 volumio[1130]: info:
Apr 08 21:57:53 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 290 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 258 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 256 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand status took 72 milliseconds
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:53 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:53 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:53 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 609ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: ------------------------------ 604ms
Apr 08 21:57:53 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 473 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 471 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":537,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 1072ms
Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 839ms
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","service":"mpd","name":"3 - Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: error: null
Apr 08 21:57:54 volumio20 volumio[1130]: info:
Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:54 volumio20 volumio[1130]: info:
Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:54 volumio20 volumio[1130]: info:
Apr 08 21:57:54 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 149 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 38 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 26 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 30 milliseconds
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":99000,"duration":369,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:54 volumio20 volumio[1130]: verbose: CURRENT POSITION 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:54 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:54 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:54 volumio20 volumio[1130]: info: ------------------------------ 545ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 466ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 457ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioNext
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::next
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 2
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand stop took 62 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 65 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand stop took 52 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand status took 36 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 33 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand clear took 34 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 31 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 29 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3"
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: 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":"Far Beyond Yon Mountain","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/03 - Far Beyond Yon Mountain.mp3","trackType":"mp3"}
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: No code
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:55 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:55 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:55 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:55 volumio20 volumio[1130]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 246ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 215ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 208ms
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 209ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3" took 195 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 68ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 61ms
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:57:55 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 171ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: sendMpdCommand play took 171 milliseconds
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 173ms
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info: ------------------------------ 172ms
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:55 volumio20 volumio[1130]: info:
Apr 08 21:57:55 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:55 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:55 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 284 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 268 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 254 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 245 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:56 volumio20 volumio[1130]: info:
Apr 08 21:57:56 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 79 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 78 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 74 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 63 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand status took 35 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 738ms
Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 886ms
Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 842ms
Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 833ms
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","service":"mpd","name":"4 - Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: error: null
Apr 08 21:57:56 volumio20 volumio[1130]: info:
Apr 08 21:57:56 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:56 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 584 milliseconds
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:56 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1280,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:56 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:56 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:56 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:56 volumio20 volumio[1130]: info: ------------------------------ 782ms
Apr 08 21:57:57 volumio20 volumio[1130]: info:
Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:57 volumio20 volumio[1130]: info:
Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 370 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 56 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand status took 47 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","service":"mpd","name":"4 - Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: error: null
Apr 08 21:57:57 volumio20 volumio[1130]: info:
Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 292 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 293 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 288 milliseconds
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264000,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:57 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:57 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 1035ms
Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 721ms
Apr 08 21:57:57 volumio20 volumio[1130]: info: ------------------------------ 719ms
Apr 08 21:57:57 volumio20 volumio[1130]: info:
Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:57 volumio20 volumio[1130]: info:
Apr 08 21:57:57 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:57:57 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:57:57 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 529 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 58 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand status took 50 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 188 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 187 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 186 milliseconds
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":330879,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331382,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":331382,"duration":479,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:57:58 volumio20 volumio[1130]: verbose: CURRENT POSITION 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:57:58 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:57:58 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 1142ms
Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 695ms
Apr 08 21:57:58 volumio20 volumio[1130]: info: ------------------------------ 660ms
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPlay
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::play index 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 3
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand stop took 60 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::play index undefined
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 20 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand stop took 7 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 50 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand clear took 50 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 40 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: info: sendMpdCommand status took 31 milliseconds
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3"
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: 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":"Were You At The Rock","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/04 - Were You At The Rock.mp3","trackType":"mp3"}
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: No code
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: 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}
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: No code
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: 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}
Apr 08 21:58:02 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: No code
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:02 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 376ms
Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 336ms
Apr 08 21:58:02 volumio20 volumio[1130]: info: ------------------------------ 333ms
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:02 volumio20 volumio[1130]: info:
Apr 08 21:58:02 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:02 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:03 volumio20 volumio[1130]: error: updateQueue error: null
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 369ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand add "INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3" took 352 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 59ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 53ms
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces system playlist update
Apr 08 21:58:03 volumio20 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Apr 08 21:58:03 volumio20 volumio[1130]: info: Ignoring MPD Status Update
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 209ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand play took 197 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 196ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 203ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 140 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 147 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 136 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 111 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info:
Apr 08 21:58:03 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 169 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 172 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 169 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 170 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 129 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: info: sendMpdCommand status took 121 milliseconds
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:03 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:03 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:03 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 595ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 733ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 697ms
Apr 08 21:58:03 volumio20 volumio[1130]: info: ------------------------------ 702ms
Apr 08 21:58:04 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 425 milliseconds
Apr 08 21:58:04 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 426 milliseconds
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:04 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1085,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:04 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:04 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: ------------------------------ 965ms
Apr 08 21:58:04 volumio20 volumio[1130]: info: ------------------------------ 964ms
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioSeek
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::seek
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: TRACKBLOCK {"uri":"mnt/INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","service":"mpd","name":"10 - Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=999&web=Aine%20Minogue/Celtic%20Medittion%20Music/extralarge&path=%2FINTERNAL%2FMedit%C3%A1cia%2FCeltic%20Meditation%20Music&metadata=false","duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2}
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::startPlaybackTimer
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: ControllerMpd::seek
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:04 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:04 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: error: null
Apr 08 21:58:05 volumio20 volumio[1130]: info:
Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:05 volumio20 volumio[1130]: info:
Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:05 volumio20 volumio[1130]: info:
Apr 08 21:58:05 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 133 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 119 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand status took 107 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 38 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 38 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 37 milliseconds
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: STATE SERVICE {"status":"play","position":0,"seek":194000,"duration":295,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:05 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService play
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus play
Apr 08 21:58:05 volumio20 volumio[1130]: info: Received an update from plugin. extracting info from payload
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:05 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 651ms
Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 640ms
Apr 08 21:58:05 volumio20 volumio[1130]: info: ------------------------------ 643ms
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioClearQueue
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::stPlaybackTimer
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::updateTrackBlock
Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrackBlock
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreStateMachine::serviceStop
Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::serviceStop
Apr 08 21:58:07 volumio20 volumio[1130]: info: ControllerMpd::stop
Apr 08 21:58:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue
Apr 08 21:58:07 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:07 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue
Apr 08 21:58:07 volumio20 volumio[1130]: info:
Apr 08 21:58:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:07 volumio20 volumio[1130]: info: sendMpdCommand stop took 131 milliseconds
Apr 08 21:58:07 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:07 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:07 volumio20 volumio[1130]: info:
Apr 08 21:58:07 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:08 volumio20 volumio[1130]: info:
Apr 08 21:58:08 volumio20 volumio[1130]: ---------------------------- MPD announces state update: player
Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::getState
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 41 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 34 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand status took 28 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseState
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 28 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: info: sendMpdCommand playlistinfo took 27 milliseconds
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: ControllerMpd::parseTrackInfo
Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: No code
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: No code
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: ControllerMpd::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::servicePushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: 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":"Easter Snow","artist":"Aine Minogue","album":"Celtic Medittion Music","uri":"INTERNAL/Meditácia/Celtic Meditation Music/10 - Easter Snow.mp3","trackType":"mp3"}
Apr 08 21:58:08 volumio20 volumio[1130]: verbose: CURRENT POSITION 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState stateService stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: No code
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreStateMachine::pushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: CorePlayQueue::getTrack 9
Apr 08 21:58:08 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushState
Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 386ms
Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 381ms
Apr 08 21:58:08 volumio20 volumio[1130]: info: ------------------------------ 379ms
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::ClearQueue
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::stop
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::clearPlayQueue
Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::saveQueue
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreCommandRouter::volumioPushQueue
Apr 08 21:58:16 volumio20 volumio[1130]: info: CoreStateMachine::addQueueItems
Apr 08 21:58:16 volumio20 volumio[1130]: info: CorePlayQueue::addQueueItems
Apr 08 21:58:16 volumio20 volumio[1130]: info: Preload queue cleared
Apr 08 21:58:16 volumio20 volumio[1130]: info: Adding Item to queue: music-library/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac)
Apr 08 21:58:16 volumio20 volumio[1130]: info: Exploding uri music-library/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac) in service mpd
Apr 08 21:58:16 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Bandari/Chinese%20Poem/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBandari%20-%20Chinese%20Poem%20%5B2015%5D%20(flac)%2FBandari%20-%20Chinese%20Poem.cue&metadata=false
Apr 08 21:58:16 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac)/Bandari - Chinese Poem.cue
Apr 08 21:58:16 volumio20 volumio[1130]: info: ALBUMART /albumart?cacheid=999&web=Bandari/Chinese%20Poem/extralarge&path=%2Fmnt%2FINTERNAL%2FMedit%C3%A1cia%2FBandari%20-%20Chinese%20Poem%20%5B2015%5D%20(flac)%2FBandari%20-%20Chinese%20Poem.cue&metadata=false
Apr 08 21:58:16 volumio20 volumio[1130]: info: URI /mnt/INTERNAL/Meditácia/Bandari - Chinese Poem [2015] (flac)/Bandari - Chinese Poem.cue
Apr 08 21:58:16 volumio20 volumio[1130]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 08 21:58:16 volumio20 volumio[1130]: Error: Unable to resolve or reject the same promise twice
Apr 08 21:58:16 volumio20 volumio[1130]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Apr 08 21:58:16 volumio20 volumio[1130]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21
Apr 08 21:58:16 volumio20 volumio[1130]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Apr 08 21:58:16 volumio20 volumio[1130]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Apr 08 21:58:16 volumio20 volumio[1130]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Apr 08 21:58:16 volumio20 volumio[1130]: at Socket.emit (events.js:315:20)
Apr 08 21:58:16 volumio20 volumio[1130]: at addChunk (internal/streams/readable.js:309:12)
Apr 08 21:58:16 volumio20 volumio[1130]: at readableAddChunk (internal/streams/readable.js:280:11)
Apr 08 21:58:16 volumio20 volumio[1130]: at Socket.Readable.push (internal/streams/readable.js:223:10)
Apr 08 21:58:16 volumio20 volumio[1130]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23)
Apr 08 21:58:16 volumio20 volumio[1130]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 08 21:58:19 volumio20 sudo[18781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 21:57
Apr 08 21:58:19 volumio20 sudo[18781]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"