-- Logs begin at Tue 2024-05-07 22:44:35 CST, end at Fri 2024-05-10 01:08:27 CST. --
May 10 01:07:00 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:00 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:00 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:00 volumio volumio[1439]: info: No code
May 10 01:07:00 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:00 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:00 volumio volumio[1439]: info: ------------------------------ 1002ms
May 10 01:07:00 volumio volumio[1439]: info: ------------------------------ 996ms
May 10 01:07:00 volumio volumio[1439]: info: ------------------------------ 989ms
May 10 01:07:01 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:01 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:01 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:01 volumio volumio[1439]: info: Adding Item to queue: http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm
May 10 01:07:01 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:01 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:01 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:01 volumio volumio[1439]: info: [1715274421776] ControllerWebradio::clearAddPlayTrack
May 10 01:07:01 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:01 volumio volumio[1439]: info: sendMpdCommand stop took 42 milliseconds
May 10 01:07:01 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:01 volumio volumio[1439]: info:
May 10 01:07:01 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:01 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:01 volumio volumio[1439]: info: sendMpdCommand clear took 15 milliseconds
May 10 01:07:01 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm"
May 10 01:07:01 volumio volumio[1439]: info:
May 10 01:07:01 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:01 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:01 volumio volumio[1439]: info:
May 10 01:07:01 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:01 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:01 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:01 volumio volumio[1439]: info: ------------------------------ 41ms
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm"
May 10 01:07:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:02 volumio volumio[1439]: info: ------------------------------ 427ms
May 10 01:07:02 volumio volumio[1439]: info: ------------------------------ 421ms
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm" took 22 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:02 volumio volumio[1439]: info: ------------------------------ 53ms
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand play took 38 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: ------------------------------ 31ms
May 10 01:07:02 volumio volumio[1439]: info: ------------------------------ 23ms
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:02 volumio volumio[1439]: info:
May 10 01:07:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand status took 85 milliseconds
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand status took 77 milliseconds
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand status took 70 milliseconds
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand clearerror took 116 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand clearerror took 101 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand clearerror took 95 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 37 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 33 milliseconds
May 10 01:07:02 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 29 milliseconds
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:02 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:02 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:02 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:02 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:02 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:03 volumio volumio[1439]: 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":"bbc_radio_fourfm","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm","trackType":""}
May 10 01:07:03 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: No code
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:03 volumio volumio[1439]: 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":"bbc_radio_fourfm","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm","trackType":""}
May 10 01:07:03 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: No code
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:03 volumio volumio[1439]: 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":"bbc_radio_fourfm","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_fourfm","trackType":""}
May 10 01:07:03 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: No code
May 10 01:07:03 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:03 volumio volumio[1439]: info: ------------------------------ 848ms
May 10 01:07:03 volumio volumio[1439]: info: ------------------------------ 829ms
May 10 01:07:03 volumio volumio[1439]: info: ------------------------------ 821ms
May 10 01:07:04 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:04 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:04 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:04 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:04 volumio volumio[1439]: info: Adding Item to queue: http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk
May 10 01:07:04 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:04 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:04 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:04 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:04 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:04 volumio volumio[1439]: info: [1715274424477] ControllerWebradio::clearAddPlayTrack
May 10 01:07:04 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:04 volumio volumio[1439]: info: sendMpdCommand stop took 31 milliseconds
May 10 01:07:04 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:04 volumio volumio[1439]: info:
May 10 01:07:04 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:04 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:04 volumio volumio[1439]: info: sendMpdCommand clear took 14 milliseconds
May 10 01:07:04 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk"
May 10 01:07:04 volumio volumio[1439]: info:
May 10 01:07:04 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:04 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:04 volumio volumio[1439]: info:
May 10 01:07:04 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:04 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:04 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:04 volumio volumio[1439]: info: ------------------------------ 42ms
May 10 01:07:04 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk"
May 10 01:07:04 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:04 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:04 volumio volumio[1439]: info:
May 10 01:07:04 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:04 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:04 volumio volumio[1439]: info: ------------------------------ 451ms
May 10 01:07:05 volumio volumio[1439]: info: ------------------------------ 444ms
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand add "http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk" took 23 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:05 volumio volumio[1439]: info:
May 10 01:07:05 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:05 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:05 volumio volumio[1439]: info:
May 10 01:07:05 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:05 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:05 volumio volumio[1439]: info: ------------------------------ 54ms
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand play took 38 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: ------------------------------ 31ms
May 10 01:07:05 volumio volumio[1439]: info: ------------------------------ 23ms
May 10 01:07:05 volumio volumio[1439]: info:
May 10 01:07:05 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:05 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand status took 43 milliseconds
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:05 volumio volumio[1439]: info:
May 10 01:07:05 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:05 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:05 volumio volumio[1439]: info:
May 10 01:07:05 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:05 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand clearerror took 45 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 38 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand status took 28 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand status took 18 milliseconds
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:05 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:05 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:05 volumio volumio[1439]: 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":"bbc_radio_five_live_online_nonuk","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk","trackType":""}
May 10 01:07:05 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:05 volumio volumio[1439]: info: No code
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:05 volumio volumio[1439]: info: ------------------------------ 388ms
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 353 milliseconds
May 10 01:07:05 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 351 milliseconds
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:05 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:05 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:05 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:05 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:06 volumio volumio[1439]: 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":"bbc_radio_five_live_online_nonuk","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk","trackType":""}
May 10 01:07:06 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: No code
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:06 volumio volumio[1439]: 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":"bbc_radio_five_live_online_nonuk","artist":null,"album":null,"uri":"http://stream.live.vc.bbcmedia.co.uk/bbc_radio_five_live_online_nonuk","trackType":""}
May 10 01:07:06 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: No code
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:06 volumio volumio[1439]: info: ------------------------------ 833ms
May 10 01:07:06 volumio volumio[1439]: info: ------------------------------ 826ms
May 10 01:07:06 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:06 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:06 volumio volumio[1439]: info: Adding Item to queue: http://vis.media-ice.musicradio.com/ClassicFMMP3
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:06 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:06 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:06 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:06 volumio volumio[1439]: info: [1715274426728] ControllerWebradio::clearAddPlayTrack
May 10 01:07:06 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:06 volumio volumio[1439]: info: sendMpdCommand stop took 36 milliseconds
May 10 01:07:06 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:06 volumio volumio[1439]: info:
May 10 01:07:06 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:06 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:06 volumio volumio[1439]: info: sendMpdCommand clear took 77 milliseconds
May 10 01:07:06 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://vis.media-ice.musicradio.com/ClassicFMMP3"
May 10 01:07:06 volumio volumio[1439]: info:
May 10 01:07:06 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:06 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:06 volumio volumio[1439]: info:
May 10 01:07:06 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:06 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:06 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:06 volumio volumio[1439]: info: ------------------------------ 73ms
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://vis.media-ice.musicradio.com/ClassicFMMP3"
May 10 01:07:08 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:08 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:08 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 1297ms
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 1291ms
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand add "http://vis.media-ice.musicradio.com/ClassicFMMP3" took 21 milliseconds
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:08 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:08 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 54ms
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand play took 37 milliseconds
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 32ms
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 23ms
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:08 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:08 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:08 volumio volumio[1439]: info:
May 10 01:07:08 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:08 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand status took 51 milliseconds
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand status took 35 milliseconds
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand status took 43 milliseconds
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 39 milliseconds
May 10 01:07:08 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 37 milliseconds
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:08 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:08 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:08 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:08 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:08 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMMP3","artist":null,"album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:08 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:08 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:08 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:08 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:08 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMMP3","artist":null,"album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:08 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:08 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:08 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:08 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:08 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:08 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:08 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:08 volumio volumio[1439]: info: ------------------------------ 312ms
May 10 01:07:09 volumio volumio[1439]: info: ------------------------------ 488ms
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 449 milliseconds
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:09 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:09 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:09 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMMP3","artist":null,"album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:09 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:09 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:09 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:09 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:09 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:09 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:09 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:09 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:09 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:09 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:09 volumio volumio[1439]: info: ------------------------------ 862ms
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:09 volumio volumio[1439]: info: ------------------------------ 710ms
May 10 01:07:09 volumio volumio[1439]: info: sendMpdCommand status took 685 milliseconds
May 10 01:07:09 volumio volumio[1439]: info: ------------------------------ 244ms
May 10 01:07:09 volumio volumio[1439]: info: sendMpdCommand status took 225 milliseconds
May 10 01:07:09 volumio volumio[1439]: info: ------------------------------ 226ms
May 10 01:07:09 volumio volumio[1439]: info: sendMpdCommand status took 203 milliseconds
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:09 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:09 volumio volumio[1439]: info:
May 10 01:07:09 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:09 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:10 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 229ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 189 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 215ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 166 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 157 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 153 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 151 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 112ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 94 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 88ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 69 milliseconds
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:10 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:10 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1080,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:10 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:10 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:10 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:10 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:10 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:10 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:10 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:10 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1583,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:10 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:10 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 1644ms
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 1175ms
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 1153ms
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:10 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:10 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:10 volumio volumio[1439]: info:
May 10 01:07:10 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 776 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 770 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 754 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 751 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 72ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 57 milliseconds
May 10 01:07:10 volumio volumio[1439]: info: ------------------------------ 51ms
May 10 01:07:10 volumio volumio[1439]: info: sendMpdCommand status took 39 milliseconds
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:10 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:10 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:10 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:10 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:10 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:10 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:10 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:10 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:11 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:11 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1880,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:11 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:11 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:11 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:11 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:11 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:11 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:11 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:11 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1983,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:11 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:11 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:11 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:11 volumio volumio[1439]: info: ------------------------------ 1901ms
May 10 01:07:11 volumio volumio[1439]: info: ------------------------------ 1886ms
May 10 01:07:11 volumio volumio[1439]: info: ------------------------------ 1764ms
May 10 01:07:11 volumio volumio[1439]: info: ------------------------------ 1737ms
May 10 01:07:12 volumio volumio[1439]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 10 01:07:12 volumio volumio[1439]: info: Retrying Login Due to Network Error
May 10 01:07:12 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 1234 milliseconds
May 10 01:07:12 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 1228 milliseconds
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:12 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:12 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:12 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:12 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2782,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"William Walton - Crown Imperial","artist":"Classic FM","album":null,"uri":"http://vis.media-ice.musicradio.com/ClassicFMMP3","trackType":""}
May 10 01:07:12 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:12 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:12 volumio volumio[1439]: info: ------------------------------ 1634ms
May 10 01:07:12 volumio volumio[1439]: info: ------------------------------ 1618ms
May 10 01:07:12 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::stPlaybackTimer
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::serviceStop
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::serviceStop
May 10 01:07:12 volumio volumio[1439]: info: [1715274432581] ControllerWebradio::stop
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:12 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:12 volumio volumio[1439]: info: Adding Item to queue: http://direct.franceculture.fr/live/franceculture-midfi.mp3
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:12 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:12 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:12 volumio volumio[1439]: info: [1715274432776] ControllerWebradio::clearAddPlayTrack
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:12 volumio volumio[1439]: info:
May 10 01:07:12 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:12 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:12 volumio volumio[1439]: info:
May 10 01:07:12 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:12 volumio volumio[1439]: info:
May 10 01:07:12 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:12 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:12 volumio volumio[1439]: info: sendMpdCommand stop took 258 milliseconds
May 10 01:07:12 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:12 volumio volumio[1439]: info: sendMpdCommand stop took 71 milliseconds
May 10 01:07:12 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:13 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:13 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:13 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:13 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:13 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 261ms
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand status took 259 milliseconds
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand status took 250 milliseconds
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand clear took 231 milliseconds
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 97ms
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand status took 86 milliseconds
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 82ms
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand status took 71 milliseconds
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://direct.franceculture.fr/live/franceculture-midfi.mp3"
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:13 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:13 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:13 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:13 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:13 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:13 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:13 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:13 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:13 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:13 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:13 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:13 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:13 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 413ms
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 407ms
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:13 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:13 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:13 volumio volumio[1439]: info:
May 10 01:07:13 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:13 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:13 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 491ms
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 443 milliseconds
May 10 01:07:13 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 441 milliseconds
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:13 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:13 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 10 01:07:13 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 766ms
May 10 01:07:13 volumio volumio[1439]: info: ------------------------------ 765ms
May 10 01:07:21 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:21 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:21 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:21 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:21 volumio volumio[1439]: info: Adding Item to queue: http://direct.francemusique.fr/live/francemusiquelacontemporaine-hifi.mp3
May 10 01:07:21 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:21 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:21 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:21 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:21 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:21 volumio volumio[1439]: info: [1715274441921] ControllerWebradio::clearAddPlayTrack
May 10 01:07:21 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:22 volumio volumio[1439]: info: MyVolumio login type: Token
May 10 01:07:23 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://direct.franceculture.fr/live/franceculture-midfi.mp3"
May 10 01:07:23 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:23 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:23 volumio volumio[1439]: info:
May 10 01:07:23 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:23 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:23 volumio volumio[1439]: info: ------------------------------ 10384ms
May 10 01:07:23 volumio volumio[1439]: info: sendMpdCommand status took 10363 milliseconds
May 10 01:07:23 volumio volumio[1439]: info: ------------------------------ 10362ms
May 10 01:07:23 volumio volumio[1439]: info: sendMpdCommand status took 10346 milliseconds
May 10 01:07:23 volumio volumio[1439]: info: sendMpdCommand stop took 1923 milliseconds
May 10 01:07:23 volumio volumio[1439]: info: sendMpdCommand add "http://direct.franceculture.fr/live/franceculture-midfi.mp3" took 31 milliseconds
May 10 01:07:23 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:23 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:23 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:23 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:23 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:23 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:23 volumio volumio[1439]: 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}
May 10 01:07:23 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:23 volumio volumio[1439]: info: No code
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:23 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:23 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:23 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:24 volumio volumio[1439]: 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}
May 10 01:07:24 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:24 volumio volumio[1439]: info: No code
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 10895ms
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 10869ms
May 10 01:07:24 volumio volumio[1439]: info:
May 10 01:07:24 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:24 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:24 volumio volumio[1439]: info:
May 10 01:07:24 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:24 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:24 volumio volumio[1439]: info:
May 10 01:07:24 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:24 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:24 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:24 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 620ms
May 10 01:07:24 volumio volumio[1439]: info: sendMpdCommand clear took 602 milliseconds
May 10 01:07:24 volumio volumio[1439]: info: sendMpdCommand play took 600 milliseconds
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 71ms
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 55ms
May 10 01:07:24 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://direct.francemusique.fr/live/francemusiquelacontemporaine-hifi.mp3"
May 10 01:07:24 volumio volumio[1439]: info:
May 10 01:07:24 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:24 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:24 volumio volumio[1439]: info:
May 10 01:07:24 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:24 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:24 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:24 volumio volumio[1439]: info: ------------------------------ 264ms
May 10 01:07:24 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:24 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:24 volumio volumio[1439]: info: Adding Item to queue: http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:24 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:24 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:24 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:24 volumio volumio[1439]: info: [1715274444839] ControllerWebradio::clearAddPlayTrack
May 10 01:07:24 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:26 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:26 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:26 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://direct.francemusique.fr/live/francemusiquelacontemporaine-hifi.mp3"
May 10 01:07:26 volumio volumio[1439]: info: ------------------------------ 1976ms
May 10 01:07:26 volumio volumio[1439]: info: ------------------------------ 1968ms
May 10 01:07:26 volumio volumio[1439]: info: sendMpdCommand stop took 1623 milliseconds
May 10 01:07:26 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:26 volumio volumio[1439]: info:
May 10 01:07:26 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:26 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:26 volumio volumio[1439]: info:
May 10 01:07:26 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:26 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:26 volumio volumio[1439]: info: sendMpdCommand add "http://direct.francemusique.fr/live/francemusiquelacontemporaine-hifi.mp3" took 29 milliseconds
May 10 01:07:26 volumio volumio[1439]: info: sendMpdCommand clear took 19 milliseconds
May 10 01:07:26 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:26 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:26 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3"
May 10 01:07:26 volumio volumio[1439]: info:
May 10 01:07:26 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:26 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:26 volumio volumio[1439]: info:
May 10 01:07:26 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:26 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:26 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:26 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:26 volumio volumio[1439]: info: ------------------------------ 60ms
May 10 01:07:26 volumio volumio[1439]: info: ------------------------------ 56ms
May 10 01:07:26 volumio volumio[1439]: info: sendMpdCommand play took 42 milliseconds
May 10 01:07:27 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3"
May 10 01:07:27 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:27 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:27 volumio volumio[1439]: info:
May 10 01:07:27 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:27 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 864ms
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 859ms
May 10 01:07:27 volumio volumio[1439]: info: sendMpdCommand add "http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3" took 21 milliseconds
May 10 01:07:27 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:27 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:27 volumio volumio[1439]: info:
May 10 01:07:27 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:27 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:27 volumio volumio[1439]: info:
May 10 01:07:27 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:27 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 50ms
May 10 01:07:27 volumio volumio[1439]: info: sendMpdCommand play took 34 milliseconds
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 28ms
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 20ms
May 10 01:07:27 volumio volumio[1439]: info:
May 10 01:07:27 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:27 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:27 volumio volumio[1439]: info:
May 10 01:07:27 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:27 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 24ms
May 10 01:07:27 volumio volumio[1439]: info: ------------------------------ 21ms
May 10 01:07:28 volumio volumio[1439]: info:
May 10 01:07:28 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:28 volumio volumio[1439]: info:
May 10 01:07:28 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:28 volumio volumio[1439]: info:
May 10 01:07:28 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand status took 64 milliseconds
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand status took 46 milliseconds
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand status took 57 milliseconds
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:28 volumio volumio[1439]: info:
May 10 01:07:28 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:28 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:28 volumio volumio[1439]: info:
May 10 01:07:28 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 291 milliseconds
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 293 milliseconds
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:28 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 121 milliseconds
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:28 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:28 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:28 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:28 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":null,"album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:28 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:28 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:28 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:28 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:28 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:28 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:28 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":null,"album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:28 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:28 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:28 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:28 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:28 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:28 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:28 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:28 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:29 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:29 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:29 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:29 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:29 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:29 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":"francemusiquelajazz-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:29 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:29 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:29 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:29 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:29 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:29 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:29 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:29 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:29 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:29 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:29 volumio volumio[1439]: info: ------------------------------ 1052ms
May 10 01:07:29 volumio volumio[1439]: info: ------------------------------ 1593ms
May 10 01:07:29 volumio volumio[1439]: info: ------------------------------ 1590ms
May 10 01:07:29 volumio volumio[1439]: info:
May 10 01:07:29 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:29 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:30 volumio volumio[1439]: info:
May 10 01:07:30 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:30 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:30 volumio volumio[1439]: info:
May 10 01:07:30 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:30 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:30 volumio volumio[1439]: info:
May 10 01:07:30 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:30 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 1441ms
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand status took 1360 milliseconds
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 119ms
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand status took 103 milliseconds
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 85ms
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand status took 52 milliseconds
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 181 milliseconds
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 179 milliseconds
May 10 01:07:30 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 177 milliseconds
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:30 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:30 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1112,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":"francemusiquelajazz-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:30 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:30 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2362,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":"francemusiquelajazz-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:30 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:30 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2362,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.mp3","artist":"francemusiquelajazz-midfi.mp3","album":null,"uri":"http://direct.francemusique.fr/live/francemusiquelajazz-hifi.mp3","trackType":"mp3"}
May 10 01:07:30 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:30 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 2184ms
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 895ms
May 10 01:07:30 volumio volumio[1439]: info: ------------------------------ 843ms
May 10 01:07:31 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::stPlaybackTimer
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::serviceStop
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::serviceStop
May 10 01:07:31 volumio volumio[1439]: info: [1715274451848] ControllerWebradio::stop
May 10 01:07:31 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:31 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:31 volumio volumio[1439]: info: Adding Item to queue: http://kdhx-ice.streamguys1.com:80/live
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:31 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:31 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:31 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: [1715274452008] ControllerWebradio::clearAddPlayTrack
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand stop took 264 milliseconds
May 10 01:07:32 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand stop took 109 milliseconds
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:32 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:32 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:32 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand status took 155 milliseconds
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand clear took 149 milliseconds
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand status took 140 milliseconds
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand status took 79 milliseconds
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://kdhx-ice.streamguys1.com:80/live"
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:32 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:32 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:32 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:32 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:32 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:32 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:32 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:32 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:32 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:32 volumio volumio[1439]: info: ------------------------------ 404ms
May 10 01:07:32 volumio volumio[1439]: info: ------------------------------ 396ms
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:32 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:32 volumio volumio[1439]: info:
May 10 01:07:32 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:32 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:32 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:32 volumio volumio[1439]: info: ------------------------------ 353ms
May 10 01:07:32 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 318 milliseconds
May 10 01:07:32 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:32 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 10 01:07:32 volumio volumio[1439]: info: ------------------------------ 517ms
May 10 01:07:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live"
May 10 01:07:34 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:34 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:34 volumio volumio[1439]: info:
May 10 01:07:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:34 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:34 volumio volumio[1439]: info: ------------------------------ 1963ms
May 10 01:07:34 volumio volumio[1439]: info: ------------------------------ 1956ms
May 10 01:07:34 volumio volumio[1439]: info: sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" took 22 milliseconds
May 10 01:07:34 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:34 volumio volumio[1439]: info:
May 10 01:07:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:34 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:34 volumio volumio[1439]: info:
May 10 01:07:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:34 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:34 volumio volumio[1439]: info: ------------------------------ 49ms
May 10 01:07:34 volumio volumio[1439]: info: sendMpdCommand play took 33 milliseconds
May 10 01:07:34 volumio volumio[1439]: info: ------------------------------ 27ms
May 10 01:07:34 volumio volumio[1439]: info: ------------------------------ 19ms
May 10 01:07:35 volumio volumio[1439]: info:
May 10 01:07:35 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:35 volumio volumio[1439]: info:
May 10 01:07:35 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:35 volumio volumio[1439]: info:
May 10 01:07:35 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand status took 46 milliseconds
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand status took 35 milliseconds
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand status took 70 milliseconds
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 47 milliseconds
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:35 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:35 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:35 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:07:35 volumio volumio[1439]: info: ------------------------------ 165ms
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 65 milliseconds
May 10 01:07:35 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 29 milliseconds
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:35 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:35 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:35 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:35 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:35 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:35 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:35 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:35 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:35 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:35 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:35 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:35 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:35 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:35 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 484ms
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 473ms
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 84ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 72 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 68ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 71 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 67ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 57 milliseconds
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:36 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:36 volumio volumio[1439]: info:
May 10 01:07:36 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 197ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 190 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 156 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 154 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 152 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 146ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 134 milliseconds
May 10 01:07:36 volumio volumio[1439]: info: ------------------------------ 130ms
May 10 01:07:36 volumio volumio[1439]: info: sendMpdCommand status took 118 milliseconds
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:36 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:36 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:36 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:36 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:36 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:36 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:36 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:36 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 960ms
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 953ms
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 938ms
May 10 01:07:37 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 693 milliseconds
May 10 01:07:37 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 683 milliseconds
May 10 01:07:37 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 673 milliseconds
May 10 01:07:37 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:37 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:37 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:37 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:37 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:37 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:37 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:37 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1075,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""}
May 10 01:07:37 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState stateService play
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus play
May 10 01:07:37 volumio volumio[1439]: info: Received an update from plugin. extracting info from payload
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:37 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 1391ms
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 1316ms
May 10 01:07:37 volumio volumio[1439]: info: ------------------------------ 1300ms
May 10 01:07:41 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::stPlaybackTimer
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::serviceStop
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::serviceStop
May 10 01:07:41 volumio volumio[1439]: info: [1715274461784] ControllerWebradio::stop
May 10 01:07:41 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:41 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:41 volumio volumio[1439]: info: Adding Item to queue: http://live-aacplus-64.kexp.org/kexp64.aac
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:41 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:41 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:41 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:41 volumio volumio[1439]: info: [1715274461940] ControllerWebradio::clearAddPlayTrack
May 10 01:07:41 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:41 volumio volumio[1439]: info:
May 10 01:07:41 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:41 volumio volumio[1439]: info: sendMpdCommand stop took 207 milliseconds
May 10 01:07:41 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand stop took 58 milliseconds
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:42 volumio volumio[1439]: info:
May 10 01:07:42 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:42 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:42 volumio volumio[1439]: info:
May 10 01:07:42 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:07:42 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:07:42 volumio volumio[1439]: info:
May 10 01:07:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:42 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand status took 63 milliseconds
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand clear took 57 milliseconds
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand status took 48 milliseconds
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand status took 39 milliseconds
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://live-aacplus-64.kexp.org/kexp64.aac"
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:07:42 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:42 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:42 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:42 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:07:42 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:42 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:42 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:07:42 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:07:42 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:07:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:42 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:07:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:42 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
May 10 01:07:42 volumio volumio[1439]: info: ------------------------------ 244ms
May 10 01:07:42 volumio volumio[1439]: info: ------------------------------ 236ms
May 10 01:07:42 volumio volumio[1439]: info:
May 10 01:07:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:42 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:42 volumio volumio[1439]: info:
May 10 01:07:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:42 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:42 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:42 volumio volumio[1439]: info: ------------------------------ 249ms
May 10 01:07:42 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 230 milliseconds
May 10 01:07:42 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo
May 10 01:07:42 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
May 10 01:07:42 volumio volumio[1439]: info: ------------------------------ 326ms
May 10 01:07:44 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:44 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:44 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:44 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:44 volumio volumio[1439]: info: Adding Item to queue: http://stream1.opb.org/kmhd.mp3
May 10 01:07:44 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:44 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:44 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:44 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:44 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:44 volumio volumio[1439]: info: [1715274464266] ControllerWebradio::clearAddPlayTrack
May 10 01:07:44 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac"
May 10 01:07:45 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:45 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:45 volumio volumio[1439]: info: ------------------------------ 2971ms
May 10 01:07:45 volumio volumio[1439]: info: ------------------------------ 2965ms
May 10 01:07:45 volumio volumio[1439]: info: sendMpdCommand stop took 971 milliseconds
May 10 01:07:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:45 volumio volumio[1439]: info:
May 10 01:07:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:45 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:45 volumio volumio[1439]: info:
May 10 01:07:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:45 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:45 volumio volumio[1439]: info: sendMpdCommand add "http://live-aacplus-64.kexp.org/kexp64.aac" took 36 milliseconds
May 10 01:07:45 volumio volumio[1439]: info: sendMpdCommand clear took 19 milliseconds
May 10 01:07:45 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://stream1.opb.org/kmhd.mp3"
May 10 01:07:45 volumio volumio[1439]: info:
May 10 01:07:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:45 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:45 volumio volumio[1439]: info:
May 10 01:07:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:45 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:45 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:45 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:45 volumio volumio[1439]: info: ------------------------------ 77ms
May 10 01:07:45 volumio volumio[1439]: info: ------------------------------ 73ms
May 10 01:07:45 volumio volumio[1439]: info: sendMpdCommand play took 60 milliseconds
May 10 01:07:47 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:47 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:47 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:47 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:47 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:47 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:47 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:48 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:48 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:48 volumio volumio[1439]: info: Adding Item to queue: https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream
May 10 01:07:48 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:48 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:48 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:48 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:48 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:48 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:48 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:48 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:48 volumio volumio[1439]: info: [1715274468088] ControllerWebradio::clearAddPlayTrack
May 10 01:07:48 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:50 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:50 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:50 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:07:50 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:50 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:50 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:07:50 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:50 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:07:51 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:07:51 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:51 volumio volumio[1439]: info: Adding Item to queue: http://mscp3.live-streams.nl:8340/jazz-flac.flac
May 10 01:07:51 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:07:51 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:07:51 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:07:51 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:07:51 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:07:51 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:51 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:07:51 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:07:51 volumio volumio[1439]: info: [1715274471106] ControllerWebradio::clearAddPlayTrack
May 10 01:07:51 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:07:52 volumio volumio[1439]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 10 01:07:52 volumio volumio[1439]: info: Retrying Login Due to Network Error
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://stream1.opb.org/kmhd.mp3"
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 10536ms
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 10531ms
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand stop took 7749 milliseconds
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand stop took 4735 milliseconds
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand add "http://stream1.opb.org/kmhd.mp3" took 80 milliseconds
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand clear took 63 milliseconds
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand clear took 63 milliseconds
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 60ms
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 52ms
May 10 01:07:55 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream"
May 10 01:07:55 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8340/jazz-flac.flac"
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: info:
May 10 01:07:55 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:07:55 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: error: updateQueue error: null
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 101ms
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 97ms
May 10 01:07:55 volumio volumio[1439]: info: ------------------------------ 93ms
May 10 01:07:55 volumio volumio[1439]: info: sendMpdCommand play took 64 milliseconds
May 10 01:07:59 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream"
May 10 01:07:59 volumio volumio[1439]: info: Preload queue cleared
May 10 01:07:59 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:07:59 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:00 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:08:00 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:00 volumio volumio[1439]: info: Adding Item to queue: http://mscp3.live-streams.nl:8360/flac.flac
May 10 01:08:00 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:08:00 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:08:00 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:00 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:08:00 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:00 volumio volumio[1439]: info: [1715274480136] ControllerWebradio::clearAddPlayTrack
May 10 01:08:00 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:08:01 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:01 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:08:01 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:01 volumio volumio[1439]: info: Adding Item to queue: http://stream-relay-geo.ntslive.net/stream2
May 10 01:08:01 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:08:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:08:01 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:01 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:08:01 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:01 volumio volumio[1439]: info: [1715274481537] ControllerWebradio::clearAddPlayTrack
May 10 01:08:01 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:08:02 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:08:02 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:02 volumio volumio[1439]: info: Adding Item to queue: http://vps.radioduepuntozero.it:8000/stream.ogg.m3u
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:02 volumio volumio[1439]: info: [1715274482222] ControllerWebradio::clearAddPlayTrack
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:08:02 volumio volumio[1439]: info: MyVolumio login type: Token
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac"
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 6355ms
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 6349ms
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand add "https://stream13.shoutcastsolutions.com/proxy/gbeaum00?mp=/stream" took 3002 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand stop took 2180 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand stop took 782 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand stop took 101 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8340/jazz-flac.flac" took 150 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 163ms
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand play took 131 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand clear took 128 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand clear took 128 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand clear took 128 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 125ms
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 115ms
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac"
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://stream-relay-geo.ntslive.net/stream2"
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u"
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:08:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:02 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:02 volumio volumio[1439]: info:
May 10 01:08:02 volumio volumio[1439]: ---------------------------- MPD announces state update: player
May 10 01:08:02 volumio volumio[1439]: info: ControllerMpd::getState
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 137ms
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 131ms
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand status took 97 milliseconds
May 10 01:08:02 volumio volumio[1439]: info: sendMpdCommand play took 90 milliseconds
May 10 01:08:02 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:08:02 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:02 volumio volumio[1439]: 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}
May 10 01:08:02 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:02 volumio volumio[1439]: info: No code
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:02 volumio volumio[1439]: info: ------------------------------ 364ms
May 10 01:08:02 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:02 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:08:02 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:08:02 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:02 volumio volumio[1439]: info: Adding Item to queue: http://shoutcast.radio24.it:8000/
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:08:02 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:03 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:08:03 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:08:03 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:03 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:03 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:08:03 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:03 volumio volumio[1439]: info: [1715274483052] ControllerWebradio::clearAddPlayTrack
May 10 01:08:03 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:08:03 volumio volumio[1439]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 10 01:08:03 volumio volumio[1439]: info: Retrying Login Due to Network Error
May 10 01:08:05 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac"
May 10 01:08:09 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:09 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::ClearQueue
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:09 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::addQueueItems
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::addQueueItems
May 10 01:08:09 volumio volumio[1439]: info: Preload queue cleared
May 10 01:08:09 volumio volumio[1439]: info: Adding Item to queue: http://174.36.206.197:8000
May 10 01:08:09 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
May 10 01:08:09 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::saveQueue
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock
May 10 01:08:09 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::play index 0
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::stop
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::play index undefined
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:09 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer
May 10 01:08:09 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:09 volumio volumio[1439]: info: [1715274489456] ControllerWebradio::clearAddPlayTrack
May 10 01:08:09 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop
May 10 01:08:13 volumio volumio[1439]: info: MyVolumio login type: Token
May 10 01:08:13 volumio volumio[1439]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred.
May 10 01:08:13 volumio volumio[1439]: info: Retrying Login Due to Network Error
May 10 01:08:16 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:16 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2"
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u"
May 10 01:08:16 volumio volumio[1439]: info: ------------------------------ 14315ms
May 10 01:08:16 volumio volumio[1439]: info: sendMpdCommand status took 14303 milliseconds
May 10 01:08:16 volumio volumio[1439]: info: ------------------------------ 14301ms
May 10 01:08:16 volumio volumio[1439]: info: sendMpdCommand status took 14288 milliseconds
May 10 01:08:16 volumio volumio[1439]: info: sendMpdCommand stop took 13792 milliseconds
May 10 01:08:16 volumio volumio[1439]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 11714 milliseconds
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::parseState
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:08:16 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:08:16 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:16 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:16 volumio volumio[1439]: 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}
May 10 01:08:16 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:16 volumio volumio[1439]: info: No code
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:16 volumio volumio[1439]: info: ControllerMpd::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::servicePushState
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:16 volumio volumio[1439]: info: CorePlayQueue::getTrack 0
May 10 01:08:16 volumio volumio[1439]: 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}
May 10 01:08:16 volumio volumio[1439]: verbose: CURRENT POSITION 0
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::syncState stateService stop
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop
May 10 01:08:16 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:16 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:17 volumio volumio[1439]: info: No code
May 10 01:08:17 volumio volumio[1439]: info: CoreStateMachine::pushState
May 10 01:08:17 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 14776ms
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 14764ms
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand stop took 7890 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand add "http://stream-relay-geo.ntslive.net/stream2" took 518 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" took 519 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand clear took 490 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand play took 488 milliseconds
May 10 01:08:17 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear
May 10 01:08:17 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:08:17 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:08:17 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd
May 10 01:08:17 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play
May 10 01:08:17 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://shoutcast.radio24.it:8000/"
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:17 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:17 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:17 volumio volumio[1439]: error: updateQueue error: null
May 10 01:08:17 volumio volumio[1439]: info:
May 10 01:08:17 volumio volumio[1439]: ---------------------------- MPD announces system playlist update
May 10 01:08:17 volumio volumio[1439]: info: Ignoring MPD Status Update
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 250ms
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 246ms
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 242ms
May 10 01:08:17 volumio volumio[1439]: info: ------------------------------ 239ms
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand clear took 216 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand play took 215 milliseconds
May 10 01:08:17 volumio volumio[1439]: info: sendMpdCommand play took 211 milliseconds
May 10 01:08:17 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://174.36.206.197:8000"
May 10 01:08:18 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "http://shoutcast.radio24.it:8000/"
May 10 01:08:23 volumio volumio[1439]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 10 01:08:23 volumio volumio[1439]: Error: connect ETIMEDOUT 108.160.163.117:80
May 10 01:08:23 volumio volumio[1439]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) {
May 10 01:08:23 volumio volumio[1439]: errno: -110,
May 10 01:08:23 volumio volumio[1439]: code: 'ETIMEDOUT',
May 10 01:08:23 volumio volumio[1439]: syscall: 'connect',
May 10 01:08:23 volumio volumio[1439]: address: '108.160.163.117',
May 10 01:08:23 volumio volumio[1439]: port: 80
May 10 01:08:23 volumio volumio[1439]: }
May 10 01:08:23 volumio volumio[1439]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 10 01:08:27 volumio sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-10 01:07
May 10 01:08:27 volumio sudo[1772]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d"
VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee"
VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623"
VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST"
VOLUMIO_VERSION="3.661"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"