Jan 10 15:21:03 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::ClearQueue Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::stPlaybackTimer Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::serviceStop Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::serviceStop Jan 10 15:21:03 volumio volumio[1147]: info: [1768026063932] ControllerWebradio::stop Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::clearPlayQueue Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::addQueueItems Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::addQueueItems Jan 10 15:21:03 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:03 volumio volumio[1147]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s23451 Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPlay Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::play index 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::play index undefined Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: [1768026063942] ControllerWebradio::clearAddPlayTrack Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand stop took 13 milliseconds Jan 10 15:21:03 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand stop took 2 milliseconds Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:03 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:03 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:03 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand clear took 3 milliseconds Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand load "https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein" Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:03 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 10 15:21:03 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:03 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:03 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:03 volumio volumio[1147]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 10 15:21:03 volumio volumio[1147]: info: ------------------------------ 11ms Jan 10 15:21:03 volumio volumio[1147]: info: ------------------------------ 11ms Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:03 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:03 volumio volumio[1147]: info: Jan 10 15:21:03 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:03 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:03 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:03 volumio volumio[1147]: info: ------------------------------ 13ms Jan 10 15:21:03 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 15:21:03 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:03 volumio volumio[1147]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 10 15:21:03 volumio volumio[1147]: info: ------------------------------ 18ms Jan 10 15:21:07 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand add "https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein" Jan 10 15:21:07 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:07 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:07 volumio volumio[1147]: info: ------------------------------ 3629ms Jan 10 15:21:07 volumio volumio[1147]: info: ------------------------------ 3628ms Jan 10 15:21:07 volumio volumio[1147]: info: Jan 10 15:21:07 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:07 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:07 volumio volumio[1147]: info: sendMpdCommand add "https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein" took 2 milliseconds Jan 10 15:21:07 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:21:07 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand play Jan 10 15:21:07 volumio volumio[1147]: info: Jan 10 15:21:07 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:07 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:07 volumio volumio[1147]: info: Jan 10 15:21:07 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:07 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:07 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:07 volumio volumio[1147]: info: sendMpdCommand play took 3 milliseconds Jan 10 15:21:07 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:07 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 104 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 104 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 105 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 113ms Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 115ms Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 12 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 1 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 125ms Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 15 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: 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":"gpb-radio-mp3-tunein","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 42ms Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 33ms Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 33ms Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:09 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:09 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:09 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:09 volumio volumio[1147]: info: Jan 10 15:21:09 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 5ms Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 16ms Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:09 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:09 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:09 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:09 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:09 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:09 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 27ms Jan 10 15:21:09 volumio volumio[1147]: info: ------------------------------ 25ms Jan 10 15:21:11 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::ClearQueue Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::stPlaybackTimer Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::serviceStop Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::serviceStop Jan 10 15:21:11 volumio volumio[1147]: info: [1768026071589] ControllerWebradio::stop Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::clearPlayQueue Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::addQueueItems Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::addQueueItems Jan 10 15:21:11 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:11 volumio volumio[1147]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s27908 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand stop took 33 milliseconds Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand status took 6 milliseconds Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 7 milliseconds Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: 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":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: No code Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: 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":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: No code Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: 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":"GPB Jazz","artist":null,"album":null,"uri":"https://gpb-geo.streamguys1.com/gpb/gpb-radio-mp3-tunein","trackType":""} Jan 10 15:21:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: No code Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:11 volumio volumio[1147]: info: ------------------------------ 39ms Jan 10 15:21:11 volumio volumio[1147]: info: ------------------------------ 39ms Jan 10 15:21:11 volumio volumio[1147]: info: ------------------------------ 39ms Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPlay Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::play index 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::play index undefined Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:21:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:11 volumio volumio[1147]: info: [1768026071806] ControllerWebradio::clearAddPlayTrack Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand stop took 2 milliseconds Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:11 volumio volumio[1147]: info: sendMpdCommand clear took 1 milliseconds Jan 10 15:21:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand load "http://playerservices.streamtheworld.com/pls/WCLKFM.pls?tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI" Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:11 volumio volumio[1147]: info: Jan 10 15:21:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:11 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:11 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:21:12 volumio volumio[1147]: info: Jan 10 15:21:12 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:12 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:12 volumio volumio[1147]: info: sendMpdCommand load "http://playerservices.streamtheworld.com/pls/WCLKFM.pls?tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI" took 309 milliseconds Jan 10 15:21:12 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:21:12 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand play Jan 10 15:21:12 volumio volumio[1147]: info: ------------------------------ 310ms Jan 10 15:21:12 volumio volumio[1147]: info: ------------------------------ 310ms Jan 10 15:21:12 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:12 volumio volumio[1147]: info: Jan 10 15:21:12 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:12 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:12 volumio volumio[1147]: info: Jan 10 15:21:12 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:12 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:12 volumio volumio[1147]: info: sendMpdCommand play took 4 milliseconds Jan 10 15:21:12 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:12 volumio volumio[1147]: info: ------------------------------ 1ms Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 125 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:13 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 126 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 125 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 26 milliseconds Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 28 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 27ms Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 27 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 27 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 26 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 25 milliseconds Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":114,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 164ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 170ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 170ms Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:13 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:13 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:13 volumio volumio[1147]: info: Jan 10 15:21:13 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 23 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 20 milliseconds Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":458,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":961,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":961,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":961,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 68ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 66ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 65ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 65ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 33ms Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 32 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 32ms Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand status took 31 milliseconds Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 15:21:13 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":961,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":961,"duration":0,"samplerate":"22.05 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:13 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:13 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:13 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:13 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 48ms Jan 10 15:21:13 volumio volumio[1147]: info: ------------------------------ 47ms Jan 10 15:21:18 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::ClearQueue Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::stPlaybackTimer Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::serviceStop Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::serviceStop Jan 10 15:21:18 volumio volumio[1147]: info: [1768026078188] ControllerWebradio::stop Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::clearPlayQueue Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::addQueueItems Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::addQueueItems Jan 10 15:21:18 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:18 volumio volumio[1147]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s7159 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand stop took 33 milliseconds Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand status took 7 milliseconds Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: 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":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:18 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: No code Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: 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":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:18 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: No code Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: 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":"WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","artist":"WCLK-FM JAZZ","album":null,"uri":"http://26443.live.streamtheworld.com:80/WCLKFM_SC?TGT=TuneIn&tdtok=eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiIsImtpZCI6ImZTeXA4In0.eyJpc3MiOiJ0aXNydiIsInN1YiI6IjIxMDY0IiwiaWF0IjoxNzY4MDI2MDcxLCJ0ZC1yZWciOmZhbHNlfQ.ZpZD5Ds477aGWxUXWOXVxhgyFvE8W1SSulaCsplKXPw&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA3MSwiaXNzIjoidGlzcnYifQ.cyEXIzcUYjg5ns8YX1vw2RCTBjZzvhEYZZFOjDDFfaI&gdpr=0","trackType":""} Jan 10 15:21:18 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: No code Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:18 volumio volumio[1147]: info: ------------------------------ 37ms Jan 10 15:21:18 volumio volumio[1147]: info: ------------------------------ 36ms Jan 10 15:21:18 volumio volumio[1147]: info: ------------------------------ 36ms Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:18 volumio volumio[1147]: info: CoreCommandRouter::volumioPlay Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::play index 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::play index undefined Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:21:18 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:18 volumio volumio[1147]: info: [1768026078432] ControllerWebradio::clearAddPlayTrack Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand stop took 2 milliseconds Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:18 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:18 volumio volumio[1147]: info: sendMpdCommand clear took 1 milliseconds Jan 10 15:21:18 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radiojar.com/mmbg3hfk2v8uv" Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:18 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:18 volumio volumio[1147]: info: Jan 10 15:21:18 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:18 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:18 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:18 volumio volumio[1147]: info: ------------------------------ 5ms Jan 10 15:21:19 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:19 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:19 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radiojar.com/mmbg3hfk2v8uv" Jan 10 15:21:19 volumio volumio[1147]: info: ------------------------------ 1190ms Jan 10 15:21:19 volumio volumio[1147]: info: ------------------------------ 1189ms Jan 10 15:21:19 volumio volumio[1147]: info: Jan 10 15:21:19 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:19 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:19 volumio volumio[1147]: info: sendMpdCommand add "http://stream.radiojar.com/mmbg3hfk2v8uv" took 1 milliseconds Jan 10 15:21:19 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:21:19 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand play Jan 10 15:21:19 volumio volumio[1147]: info: Jan 10 15:21:19 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:19 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:19 volumio volumio[1147]: info: Jan 10 15:21:19 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:19 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:19 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:21:19 volumio volumio[1147]: info: sendMpdCommand play took 4 milliseconds Jan 10 15:21:19 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:19 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 122 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 124 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 123 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 136ms Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 139ms Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 140ms Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 14 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 14 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 33ms Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 33ms Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: 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":"mmbg3hfk2v8uv","artist":null,"album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 41ms Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:20 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:20 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:20 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:20 volumio volumio[1147]: info: Jan 10 15:21:20 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 8ms Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 8 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 7ms Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 7 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 6ms Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:20 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mmbg3hfk2v8uv","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mmbg3hfk2v8uv","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":858,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"mmbg3hfk2v8uv","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:20 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:20 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:20 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 26ms Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 25ms Jan 10 15:21:20 volumio volumio[1147]: info: ------------------------------ 25ms Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:24 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:24 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:24 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:24 volumio volumio[1147]: info: Jan 10 15:21:24 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:24 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:24 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:24 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:24 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:24 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:24 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:24 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:24 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 17ms Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 16ms Jan 10 15:21:24 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 12 milliseconds Jan 10 15:21:24 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:24 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:24 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:24 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:24 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:24 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:24 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:24 volumio volumio[1147]: info: ------------------------------ 22ms Jan 10 15:21:33 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::ClearQueue Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::stPlaybackTimer Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::serviceStop Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::serviceStop Jan 10 15:21:33 volumio volumio[1147]: info: [1768026093423] ControllerWebradio::stop Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::clearPlayQueue Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::addQueueItems Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::addQueueItems Jan 10 15:21:33 volumio volumio[1147]: info: Preload queue cleared Jan 10 15:21:33 volumio volumio[1147]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s307363 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand stop took 35 milliseconds Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand status took 5 milliseconds Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: 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":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:33 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: No code Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: 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":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:33 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: No code Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: 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":"","artist":"mmbg3hfk2v8uv","album":null,"uri":"http://stream.radiojar.com/mmbg3hfk2v8uv","trackType":""} Jan 10 15:21:33 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState stateService stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: No code Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:33 volumio volumio[1147]: info: ------------------------------ 35ms Jan 10 15:21:33 volumio volumio[1147]: info: ------------------------------ 34ms Jan 10 15:21:33 volumio volumio[1147]: info: ------------------------------ 34ms Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPushQueue Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::saveQueue Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::updateTrackBlock Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrackBlock Jan 10 15:21:33 volumio volumio[1147]: info: CoreCommandRouter::volumioPlay Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::play index 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::stop Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::play index undefined Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: CoreStateMachine::startPlaybackTimer Jan 10 15:21:33 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:33 volumio volumio[1147]: info: [1768026093652] ControllerWebradio::clearAddPlayTrack Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand stop Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand stop took 1 milliseconds Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand clear Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:33 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:33 volumio volumio[1147]: info: sendMpdCommand clear took 0 milliseconds Jan 10 15:21:33 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand load "http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo" Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:33 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:33 volumio volumio[1147]: info: Jan 10 15:21:33 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:33 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:33 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:33 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand add "http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo" Jan 10 15:21:35 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:35 volumio volumio[1147]: error: updateQueue error: null Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 1453ms Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 1453ms Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:35 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand add "http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo" took 3 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand play Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:35 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:35 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 5ms Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand play took 4 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 123 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 122 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 120 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus stop Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:35 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 130ms Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 132ms Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: info: Jan 10 15:21:35 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand status took 1 milliseconds Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:35 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:35 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 148ms Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 29ms Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 10 15:21:35 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:35 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":";stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","artist":null,"album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:35 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:35 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:35 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 31ms Jan 10 15:21:35 volumio volumio[1147]: info: ------------------------------ 31ms Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:21:36 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:21:36 volumio volumio[1147]: info: Jan 10 15:21:36 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 8ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 7 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 7ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 6 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 6ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 6 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 5ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":601,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 19ms Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 10 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:36 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:21:36 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:21:36 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Mariah Carey - One Sweet Day ft Boys II Men","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:21:36 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:21:36 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:21:36 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 43ms Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 42ms Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 42ms Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 40ms Jan 10 15:21:36 volumio volumio[1147]: info: ------------------------------ 40ms Jan 10 15:21:49 volumio volumio[1147]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 10 15:21:49 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 10 15:21:50 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 10 15:21:50 volumio sudo[4542]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 10 15:21:50 volumio sudo[4542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio sudo[4547]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 15:21:50 volumio sudo[4547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio sudo[4542]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4547]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4553]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 15:21:50 volumio sudo[4553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio sudo[4553]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4561]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 10 15:21:50 volumio sudo[4561]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 10 15:21:50 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 10 15:21:50 volumio sudo[4561]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 10 15:21:50 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio sudo[4573]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 10 15:21:50 volumio sudo[4573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:50 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4573]: pam_unix(sudo:session): session closed for user root Jan 10 15:21:50 volumio sudo[4575]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 10 15:21:50 volumio sudo[4575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:21:52 volumio sudo[4575]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:11 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:11 volumio volumio[1147]: info: Jan 10 15:22:11 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 7ms Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 2ms Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand status took 2 milliseconds Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:22:11 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:11 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:11 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36342,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1055 The King - 1055The King Basic Drop","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:11 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:11 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36342,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1055 The King - 1055The King Basic Drop","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:11 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:11 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:11 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36342,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1055 The King - 1055The King Basic Drop","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:11 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:11 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:11 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 22ms Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 20ms Jan 10 15:22:11 volumio volumio[1147]: info: ------------------------------ 19ms Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:14 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:14 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces system playlist update Jan 10 15:22:14 volumio volumio[1147]: info: Ignoring MPD Status Update Jan 10 15:22:14 volumio volumio[1147]: info: Jan 10 15:22:14 volumio volumio[1147]: ---------------------------- MPD announces state update: player Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::getState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand status Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand status took 4 milliseconds Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 3ms Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 4ms Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand status took 3 milliseconds Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseState Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:14 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:14 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39217,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lil Kim - Not Tonight Remix Angie Martinez Missy Elliott Da Brat Lisa Left Eye Lopes","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:14 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:14 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:14 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:14 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39217,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lil Kim - Not Tonight Remix Angie Martinez Missy Elliott Da Brat Lisa Left Eye Lopes","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:14 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:14 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 17ms Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 17ms Jan 10 15:22:14 volumio volumio[1147]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 10 15:22:14 volumio volumio[1147]: verbose: ControllerMpd::parseTrackInfo Jan 10 15:22:14 volumio volumio[1147]: info: ControllerMpd::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::servicePushState Jan 10 15:22:14 volumio volumio[1147]: info: CorePlayQueue::getTrack 0 Jan 10 15:22:14 volumio volumio[1147]: verbose: STATE SERVICE {"status":"play","position":0,"seek":39217,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Lil Kim - Not Tonight Remix Angie Martinez Missy Elliott Da Brat Lisa Left Eye Lopes","artist":"105.5 FM/AM 1430 The King - Atlanta's #1 Station","album":null,"uri":"http://cast5.servcast.net:4299/;stream.mp3?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=0&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc2ODAyNjA5MywiaXNzIjoidGlzcnYifQ.G0ossjuR55I5IajvTWWIg-_a2JUHoYKmooFA0sqEIEo","trackType":""} Jan 10 15:22:14 volumio volumio[1147]: verbose: CURRENT POSITION 0 Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState stateService play Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::syncState currentStatus play Jan 10 15:22:14 volumio volumio[1147]: info: Received an update from plugin. extracting info from payload Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreStateMachine::pushState Jan 10 15:22:14 volumio volumio[1147]: info: CoreCommandRouter::volumioPushState Jan 10 15:22:14 volumio volumio[1147]: info: ------------------------------ 26ms Jan 10 15:22:51 volumio volumio[1147]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Jan 10 15:22:51 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Jan 10 15:22:52 volumio sudo[4699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Jan 10 15:22:52 volumio sudo[4699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:52 volumio sudo[4699]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:52 volumio volumio[1147]: info: Permissions for /etc/hostapd/hostapd.conf Jan 10 15:22:52 volumio volumio[1147]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jan 10 15:22:52 volumio volumio[1147]: info: Discovery: Restarting Advertising due to device name change Jan 10 15:22:52 volumio sudo[4702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jan 10 15:22:52 volumio sudo[4702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:52 volumio systemd[1]: Stopping wireless.service - Wireless Services... Jan 10 15:22:52 volumio systemd[1]: wireless.service: Deactivated successfully. Jan 10 15:22:52 volumio systemd[1]: Stopped wireless.service - Wireless Services. Jan 10 15:22:52 volumio systemd[1]: wireless.service: Consumed 8min 47.190s CPU time. Jan 10 15:22:53 volumio systemd[1]: Starting wireless.service - Wireless Services... Jan 10 15:22:53 volumio wireless.js[4709]: WIRELESS.JS: Single Network Mode enabled, only one network device can be active at a time between ethernet and wireless Jan 10 15:22:53 volumio wireless.js[4709]: WIRELESS.JS: Wired network status changed to: ---connected--- Jan 10 15:22:53 volumio wireless.js[4709]: WIRELESS.JS: Wireless.js initializing wireless flow Jan 10 15:22:53 volumio wireless.js[4709]: WIRELESS.JS: Cleaning previous... Jan 10 15:22:53 volumio sudo[4727]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ip addr flush dev wlan0 Jan 10 15:22:53 volumio sudo[4727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 10 15:22:53 volumio sudo[4727]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:53 volumio sudo[4729]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/ifconfig wlan0 down Jan 10 15:22:53 volumio sudo[4729]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 10 15:22:53 volumio volumio[1147]: info: Discovery: A device disappeared from network Jan 10 15:22:53 volumio volumio[1147]: info: Discovery: Device volumio disappeared from network Jan 10 15:22:53 volumio volumio[1147]: info: Discovery: A device disappeared from network Jan 10 15:22:54 volumio sudo[4729]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:54 volumio wireless.js[4709]: WIRELESS.JS: Stopped aP Jan 10 15:22:54 volumio sudo[4737]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 15:22:54 volumio sudo[4737]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:54 volumio sudo[4737]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:54 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 10 15:22:54 volumio sudo[4739]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jan 10 15:22:54 volumio sudo[4739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:54 volumio sudo[4739]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:54 volumio sudo[4747]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 15:22:54 volumio sudo[4747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:54 volumio sudo[4747]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:54 volumio sudo[4749]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jan 10 15:22:54 volumio sudo[4749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:57 volumio sudo[4749]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:57 volumio wireless.js[4709]: WIRELESS.JS: SETTING APPROPRIATE REG DOMAIN: JP Jan 10 15:22:57 volumio sudo[4755]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jan 10 15:22:57 volumio sudo[4755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:57 volumio sudo[4755]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:57 volumio sudo[4757]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set JP Jan 10 15:22:57 volumio sudo[4757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 10 15:22:57 volumio sudo[4757]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:57 volumio wireless.js[4709]: WIRELESS.JS: SUCCESSFULLY SET NEW REGDOMAIN: JP Jan 10 15:22:57 volumio wireless.js[4709]: WIRELESS.JS: Single Network Mode: Wired network active, not starting wireless flow Jan 10 15:22:57 volumio wireless.js[4709]: WIRELESS.JS: Notified systemd about wireless ready Jan 10 15:22:57 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 10 15:22:57 volumio systemd[1]: Started wireless.service - Wireless Services. Jan 10 15:22:57 volumio sudo[4702]: pam_unix(sudo:session): session closed for user root Jan 10 15:22:57 volumio volumio[1147]: info: Discovery: Started advertising with name: Volumio Jan 10 15:22:58 volumio volumio[1147]: compat.c: read() failed: Resource temporarily unavailable Jan 10 15:22:58 volumio volumio[1147]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:22:58 volumio volumio[1147]: Error: dns service error: unknown Jan 10 15:22:58 volumio volumio[1147]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Jan 10 15:22:58 volumio volumio[1147]: errorCode: -65537 Jan 10 15:22:58 volumio volumio[1147]: } Jan 10 15:22:58 volumio volumio[1147]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 10 15:22:59 volumio sudo[4783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-10 15:21' Jan 10 15:22:59 volumio sudo[4783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"