-- 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"