Jun 01 12:49:00 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:00 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:00 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s16223 Jun 01 12:49:00 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:01 volumio-vonku volumio[1171]: info: [1780310941071] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:01 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:01 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 3 milliseconds Jun 01 12:49:01 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 1 milliseconds Jun 01 12:49:01 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "http://www.streamakaci.com/radios/freedom.m3u" Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:01 volumio-vonku volumio[1171]: info: sendMpdCommand load "http://www.streamakaci.com/radios/freedom.m3u" took 603 milliseconds Jun 01 12:49:01 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:01 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 605ms Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 604ms Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Jun 01 12:49:01 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:01 volumio-vonku volumio[1171]: info: sendMpdCommand play took 3 milliseconds Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:01 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 14 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 15 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 14 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 18ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.076+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.077+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.079+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.079+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.079+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.080+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.082+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.083+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 70ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 75ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 58 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.152+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=154 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.152+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.153+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=154 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.153+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.155+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=154 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.155+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=154 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.156+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.156+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 129ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 126ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 95 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"Radio Freedom (http://www.freedom.fr/)","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.216+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.216+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.216+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.217+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 164ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Jun 01 12:49:02 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 14ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 13 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 5ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 4 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:02 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:02 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:02 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.253+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.253+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.254+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.254+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.254+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.255+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.255+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.255+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.256+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=251 volume=70 Jun 01 12:49:02 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:02.256+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://freedomice.streamakaci.com/freedom.mp3 title="Radio Freedom FM" Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 54ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 45ms Jun 01 12:49:02 volumio-vonku volumio[1171]: info: ------------------------------ 44ms Jun 01 12:49:05 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 34. Jun 01 12:49:05 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:05 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:05 volumio-vonku upmpdcli[2784]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:49:05 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:49:05 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:49:06 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::stPlaybackTimer Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::serviceStop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::serviceStop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: [1780310948238] ControllerWebradio::stop Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s271549 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.242+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.244+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://www.streamakaci.com/radios/freedom.m3u title="Radio Freedom FM" Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 36 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand status took 8 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand status took 2 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 18 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: 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":"freedom.mp3","artist":"FREEDOM","album":null,"uri":"http://freedomice.streamakaci.com/freedom.mp3","trackType":"mp3"} Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.351+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.352+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.354+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.355+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.355+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.355+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.355+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.355+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.356+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:08.357+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id= title= Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ------------------------------ 93ms Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ------------------------------ 90ms Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ------------------------------ 87ms Jun 01 12:49:08 volumio-vonku volumio[1171]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:08 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:08 volumio-vonku volumio[1171]: info: [1780310948654] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 2 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:08 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 0 milliseconds Jun 01 12:49:08 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "http://173.208.177.90:9322/" Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Jun 01 12:49:08 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:08 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:08 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:08 volumio-vonku volumio[1171]: info: ------------------------------ 6ms Jun 01 12:49:13 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:13 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:13 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s13634 Jun 01 12:49:13 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: [1780310954060] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:14 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:14 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:14 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:14 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s13634 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:14 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:14 volumio-vonku volumio[1171]: info: [1780310954973] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:14 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:16 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:16 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:16 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:16 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:17 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:17 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:17 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s114564 Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:17 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:17 volumio-vonku volumio[1171]: info: [1780310957244] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:17 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "http://173.208.177.90:9322/" Jun 01 12:49:19 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:19 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:19 volumio-vonku volumio[1171]: info: ------------------------------ 10748ms Jun 01 12:49:19 volumio-vonku volumio[1171]: info: ------------------------------ 10747ms Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 5349 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 4437 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 2166 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand add "http://173.208.177.90:9322/" took 3 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 3 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 3 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 2 milliseconds Jun 01 12:49:19 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" Jun 01 12:49:19 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "http://lowstream.radio-freedom.co.uk:64" Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Jun 01 12:49:19 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:19 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:20 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 35. Jun 01 12:49:20 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:20 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:20 volumio-vonku upmpdcli[2807]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:49:20 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:49:20 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:49:24 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:24 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:24 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s331393 Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:24 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:24 volumio-vonku volumio[1171]: info: [1780310964631] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:24 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:26 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:27 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:27 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:27 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s331393 Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:27 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:27 volumio-vonku volumio[1171]: info: [1780310967078] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:27 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10999ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10998ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10998ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10998ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand play took 10997 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "http://lowstream.radio-freedom.co.uk:64" Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10996ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 10995ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 5782 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 3336 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" took 4 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://audio-edge-ey5nr.ams.s.radiomast.io/3ccc1156-fcf8-4ba7-9a0c-28e3a465e1ae" took 4 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand add "http://lowstream.radio-freedom.co.uk:64" took 4 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:30 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:30 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:30 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 8ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 8ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 8ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 7 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 8 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand play took 7 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand play took 7 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: sendMpdCommand play took 7 milliseconds Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:30 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Jun 01 12:49:30 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:30 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 5ms Jun 01 12:49:30 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:31 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:31 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 867ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 866ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" took 142 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" took 1 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand play took 3 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand play took 2 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 2 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 1 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 12ms Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.558+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.559+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.561+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.561+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.561+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.561+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.563+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 25ms Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.566+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 25ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:31 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 20 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 19 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 17 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 16 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 15 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand status took 13 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"stop","position":1,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.719+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs= volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.720+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=171 volume=70 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.723+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio[1171]: info: [1780310971723] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 44ms Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.720+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.735+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=0 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.735+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 62 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 61 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 57 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 56 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 45 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.787+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=39 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.787+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.791+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=39 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.791+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=39 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.792+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=39 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.792+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=39 volume=70 Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:31.792+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 115ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 114ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 115ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 109ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 109ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 43 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 18ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: touch_display: Setting screensaver timeout to 300 seconds. Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s339071 Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:31 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:31 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 141ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 140ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://streams1.blackradiohosting.de/8016/stream" took 3 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:31 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: sendMpdCommand play took 3 milliseconds Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Jun 01 12:49:31 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:31 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 10 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://streams1.blackradiohosting.de/8016/stream"; avformat_open_input() failed: Invalid data found when processing input Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 10 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 8 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 7 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.132+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=371 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.133+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.134+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=371 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.134+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=371 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.135+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.135+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 33ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 41 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 41 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://streams1.blackradiohosting.de/8016/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.190+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.190+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.191+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.191+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.191+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.193+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.193+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.193+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.193+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.194+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.196+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.198+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.198+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.199+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.199+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.200+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.200+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.200+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.200+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.200+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.206+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.206+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=432 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.207+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 112ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 112ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 113ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 109ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 110ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: [1780310972277] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 3 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 1 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://s03.svrdedicado.org:6790/stream" Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://s03.svrdedicado.org:6790/stream" Jun 01 12:49:32 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:32 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 472ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 471ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://s03.svrdedicado.org:6790/stream" took 2 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand play took 2 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 128 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 4 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: info: Jun 01 12:49:32 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 128 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 10 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 128 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 11 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 9 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 9 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 6 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.912+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=568 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.912+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=568 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.913+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.913+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.912+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=568 volume=70 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 30ms Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.918+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand status took 41 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 39 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 37 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.964+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.965+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.965+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.967+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.967+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.967+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.966+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.967+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=655 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.968+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.969+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 91ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 97ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 97ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ------------------------------ 94ms Jun 01 12:49:32 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 50 milliseconds Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:32 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:32 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.998+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=709 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.998+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.999+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=709 volume=70 Jun 01 12:49:32 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:32.999+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=709 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.000+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.000+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 113ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s339071 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: [1780310973126] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 2 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 2 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "https://s03.svrdedicado.org:6790/stream" Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand add "https://s03.svrdedicado.org:6790/stream" Jun 01 12:49:33 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:33 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 135ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 134ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand add "https://s03.svrdedicado.org:6790/stream" took 2 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand play took 2 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 130 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: info: Jun 01 12:49:33 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:33 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 130 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 8 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: error: MPD returned error for command status: Failed to decode "https://s03.svrdedicado.org:6790/stream"; Failed to connect to s03.svrdedicado.org port 6790 after 130 ms: Couldn't connect to server: Couldn't connect to server Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 7 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 7 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 7 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand status took 4 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.421+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=250 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.421+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=250 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.422+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.422+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=250 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.422+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.422+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 23ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 35 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand clearerror took 36 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: 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":"stream","artist":null,"album":null,"uri":"https://s03.svrdedicado.org:6790/stream","trackType":""} Jun 01 12:49:33 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: No code Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:33 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.477+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.480+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.481+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.481+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.482+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.482+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_STOPPED positionMs=320 volume=70 Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.484+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.485+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.485+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:33.485+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=https://streams1.blackradiohosting.de/8016/stream title=Radio-For-You Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 90ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 91ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 92ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 89ms Jun 01 12:49:33 volumio-vonku volumio[1171]: info: ------------------------------ 89ms Jun 01 12:49:35 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 36. Jun 01 12:49:35 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:35 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:35 volumio-vonku upmpdcli[2851]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:49:35 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:49:35 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:49:36 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:36 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:36 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::ClearQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::clearPlayQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::addQueueItems Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::addQueueItems Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Preload queue cleared Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s135541 Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::saveQueue Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::updateTrackBlock Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrackBlock Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPlay Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::play index 0 Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::stop Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::play index undefined Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CoreStateMachine::startPlaybackTimer Jun 01 12:49:38 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:38 volumio-vonku volumio[1171]: info: [1780310978955] ControllerWebradio::clearAddPlayTrack Jun 01 12:49:38 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand stop Jun 01 12:49:38 volumio-vonku volumio[1171]: info: sendMpdCommand stop took 2 milliseconds Jun 01 12:49:38 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand clear Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Jun 01 12:49:38 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:38 volumio-vonku volumio[1171]: info: sendMpdCommand clear took 1 milliseconds Jun 01 12:49:38 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand load "http://rtvradio.sytes.net:8000/listen.m3u?sid=6Station" Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Jun 01 12:49:38 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Jun 01 12:49:38 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:38 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:38 volumio-vonku volumio[1171]: error: updateQueue error: null Jun 01 12:49:38 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand load "http://rtvradio.sytes.net:8000/listen.m3u?sid=6Station" took 185 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 185ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 186ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 2ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand play took 1 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 1ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 14 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 15 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 14 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 1 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus stop Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 18ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 1 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.346+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.346+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.347+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.347+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.347+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.347+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=0 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.348+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.348+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.349+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.350+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.350+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.350+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 37ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 38ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 26ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: 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":"top","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.368+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=52 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.369+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=52 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.370+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=52 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.371+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=52 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 45ms Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.372+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="RTVRadio Top 1000" Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 42ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 12ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 11 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 4 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 3ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces system playlist update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Ignoring MPD Status Update Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Jun 01 12:49:39 volumio-vonku volumio[1171]: ---------------------------- MPD announces state update: player Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::getState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand status Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 7ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 6 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 5 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 4ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand status took 3 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseState Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.477+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.477+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.477+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.477+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=150 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.479+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 45ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 38ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 37ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: ControllerMpd::parseTrackInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ControllerMpd::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::servicePushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CorePlayQueue::getTrack 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: STATE SERVICE {"status":"play","position":0,"seek":881,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Deborah Harry - I Want That Man","artist":"RTVRadio Top 10001","album":null,"uri":"http://rtvradio.sytes.net:8000/top","trackType":""} Jun 01 12:49:39 volumio-vonku volumio[1171]: verbose: CURRENT POSITION 0 Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState stateService play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::syncState currentStatus play Jun 01 12:49:39 volumio-vonku volumio[1171]: info: Received an update from plugin. extracting info from payload Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreStateMachine::pushState Jun 01 12:49:39 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioPushState Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.515+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.516+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.517+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.517+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.518+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.518+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.518+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.520+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.518+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" state=STATUS_PLAYING positionMs=194 volume=70 Jun 01 12:49:39 volumio-vonku volumio5-onboarding[1498]: time=2026-06-01T12:49:39.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.51:45252,00:00:00:00:00:00%01 @ 0x22f8ab0" id=http://rtvradio.sytes.net:8000/top title="Deborah Harry - I Want That Man" Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 78ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 75ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: ------------------------------ 75ms Jun 01 12:49:39 volumio-vonku volumio[1171]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 01 12:49:45 volumio-vonku nmbd[1123]: [2026/06/01 12:49:45.784045, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 01 12:49:45 volumio-vonku nmbd[1123]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.60 for name WORKGROUP<1d>. Jun 01 12:49:45 volumio-vonku nmbd[1123]: This response was from IP 192.168.2.50, reporting an IP address of 192.168.2.50. Jun 01 12:49:46 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:50 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 37. Jun 01 12:49:50 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:50 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:49:50 volumio-vonku upmpdcli[2874]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:49:50 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:49:50 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:49:56 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:49:56 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:56 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:49:57 volumio-vonku volumio[1171]: info: Saving local item radio-favourites Jun 01 12:49:57 volumio-vonku volumio[1171]: info: Pushing Favourites {"uri":"http://opml.radiotime.com/Tune.ashx?id=s135541","favourite":false} Jun 01 12:50:06 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 38. Jun 01 12:50:06 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:50:06 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:50:06 volumio-vonku upmpdcli[2903]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:50:06 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:50:06 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:50:06 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:50:16 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:50:16 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:50:16 volumio-vonku volumio[1171]: info: Listing playlists Jun 01 12:50:21 volumio-vonku systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 39. Jun 01 12:50:21 volumio-vonku systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:50:21 volumio-vonku systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 01 12:50:21 volumio-vonku upmpdcli[2919]: Could not open config: /tmp/upmpdcli.conf Jun 01 12:50:21 volumio-vonku systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 01 12:50:21 volumio-vonku systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 01 12:50:22 volumio-vonku volumio[1171]: Searching all installed plugins Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Jun 01 12:50:22 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Jun 01 12:50:22 volumio-vonku volumio[1171]: Cannot compose Albumart path Jun 01 12:50:22 volumio-vonku volumio[1171]: Cannot compose Albumart path Jun 01 12:50:22 volumio-vonku volumio[1171]: Cannot compose Albumart path Jun 01 12:50:22 volumio-vonku volumio[1171]: Cannot compose Albumart path Jun 01 12:50:22 volumio-vonku volumio[1171]: Cannot compose Albumart path Jun 01 12:50:22 volumio-vonku volumio[1171]: info: All search sources collected, pushing search results Jun 01 12:50:24 volumio-vonku volumio[1171]: Searching all installed plugins Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Jun 01 12:50:24 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Jun 01 12:50:25 volumio-vonku volumio[1171]: info: All search sources collected, pushing search results Jun 01 12:50:26 volumio-vonku volumio[1171]: Searching all installed plugins Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Jun 01 12:50:26 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Jun 01 12:50:26 volumio-vonku volumio[1171]: info: CoreCommandRouter::volumioGetState Jun 01 12:50:26 volumio-vonku volumio[1171]: info: All search sources collected, pushing search results Jun 01 12:50:26 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:27 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:27 volumio-vonku volumio[1171]: Searching all installed plugins Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: webradio , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: CoreCommandRouter::executeOnPlugin: randomizer , search Jun 01 12:50:27 volumio-vonku volumio[1171]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin randomizer Jun 01 12:50:27 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:28 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:28 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:28 volumio-vonku volumio[1171]: info: All search sources collected, pushing search results Jun 01 12:50:28 volumio-vonku volumio[1171]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jun 01 12:50:28 volumio-vonku volumio[1171]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 12:50:28 volumio-vonku volumio[1171]: TypeError: Cannot read properties of null (reading 'slice') Jun 01 12:50:28 volumio-vonku volumio[1171]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Jun 01 12:50:28 volumio-vonku volumio[1171]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Jun 01 12:50:28 volumio-vonku volumio[1171]: at Object.onceWrapper (node:events:629:26) Jun 01 12:50:28 volumio-vonku volumio[1171]: at ClientRequest.emit (node:events:514:28) Jun 01 12:50:28 volumio-vonku volumio[1171]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Jun 01 12:50:28 volumio-vonku volumio[1171]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Jun 01 12:50:28 volumio-vonku volumio[1171]: at TLSSocket.socketOnData (node:_http_client:535:22) Jun 01 12:50:28 volumio-vonku volumio[1171]: at TLSSocket.emit (node:events:514:28) Jun 01 12:50:28 volumio-vonku volumio[1171]: at addChunk (node:internal/streams/readable:343:12) Jun 01 12:50:28 volumio-vonku volumio[1171]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 01 12:50:28 volumio-vonku volumio[1171]: at Readable.push (node:internal/streams/readable:253:10) Jun 01 12:50:28 volumio-vonku volumio[1171]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Jun 01 12:50:28 volumio-vonku volumio[1171]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 01 12:50:28 volumio-vonku sudo[2949]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-01 12:49' Jun 01 12:50:28 volumio-vonku sudo[2949]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"