-- Logs begin at Thu 2019-02-14 18:11:58 HKT, end at Wed 2026-06-03 08:55:06 HKT. -- Jun 03 08:54:11 volumio641120 volumio[1075]: info: Preload queue cleared Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::ClearQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::stop Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::updateTrackBlock Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrackBlock Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::stPlaybackTimer Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::serviceStop Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::serviceStop Jun 03 08:54:11 volumio641120 volumio[1075]: info: [1780448051724] ControllerWebradio::stop Jun 03 08:54:11 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::clearPlayQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::saveQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::addQueueItems Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::addQueueItems Jun 03 08:54:11 volumio641120 volumio[1075]: info: Preload queue cleared Jun 03 08:54:11 volumio641120 volumio[1075]: info: Adding Item to queue: http://stream.radioparadise.com/world-etc-flacm Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 08:54:11 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:11.728+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:11 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:11.728+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:11 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:11.728+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream-dc1.radioparadise.com/rp_192m.ogg title="Radio Paradise" Jun 03 08:54:11 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:11.728+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream-dc1.radioparadise.com/rp_192m.ogg title="Radio Paradise" Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::saveQueue Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::updateTrackBlock Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrackBlock Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPlay Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::play index 0 Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::stop Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::play index undefined Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:11 volumio641120 volumio[1075]: info: CoreStateMachine::startPlaybackTimer Jun 03 08:54:11 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:11 volumio641120 volumio[1075]: info: [1780448051735] ControllerWebradio::clearAddPlayTrack Jun 03 08:54:11 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:11 volumio641120 volumio[1075]: info: sendMpdCommand stop took 19 milliseconds Jun 03 08:54:11 volumio641120 volumio[1075]: info: sendMpdCommand stop took 9 milliseconds Jun 03 08:54:11 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 08:54:11 volumio641120 volumio[1075]: info: Jun 03 08:54:11 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:11 volumio641120 volumio[1075]: info: sendMpdCommand clear took 4 milliseconds Jun 03 08:54:11 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://stream.radioparadise.com/world-etc-flacm" Jun 03 08:54:11 volumio641120 volumio[1075]: info: Jun 03 08:54:11 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Jun 03 08:54:11 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Jun 03 08:54:11 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:11 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:11 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:11 volumio641120 volumio[1075]: info: ------------------------------ 50ms Jun 03 08:54:11 volumio641120 volumio[1075]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 03 08:54:12 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" Jun 03 08:54:12 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:12 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:12 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:12 volumio641120 volumio[1075]: info: Jun 03 08:54:12 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:12 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 737ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 736ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 736ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: sendMpdCommand add "http://stream.radioparadise.com/world-etc-flacm" took 2 milliseconds Jun 03 08:54:12 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 08:54:12 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Jun 03 08:54:12 volumio641120 volumio[1075]: info: Jun 03 08:54:12 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:12 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 3ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: sendMpdCommand play took 2 milliseconds Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: Jun 03 08:54:12 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:12 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:12 volumio641120 volumio[1075]: info: Jun 03 08:54:12 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:12 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 1ms Jun 03 08:54:12 volumio641120 volumio[1075]: info: ------------------------------ 1ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 03 08:54:13 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 03 08:54:13 volumio641120 volumio[1075]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Jun 03 08:54:13 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 03 08:54:13 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 03 08:54:13 volumio641120 volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 29 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 29 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 28 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 27 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 5 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 55ms Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.144+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.144+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.145+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.146+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.147+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 68ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 69ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 69ms Jun 03 08:54:13 volumio641120 volumio[1075]: error: FusionDsp - WebSocket error: [object Object] Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 32 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":null,"album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.195+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.198+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.198+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.199+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.199+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.200+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.197+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.201+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 94ms Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.203+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.204+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 93ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 93ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 93ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 4ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 4 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 4ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 1 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:13 volumio641120 volumio[1075]: info: Jun 03 08:54:13 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand status took 1 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: 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":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.295+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.296+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.297+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.297+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.297+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.298+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.298+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.298+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.299+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.299+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.298+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.299+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.299+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 35ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 33ms Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.300+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.300+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 33ms Jun 03 08:54:13 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:13 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":183,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"world-etc-flacm","artist":"Radio Paradise: Global Mix (flac)","album":null,"uri":"http://stream.radioparadise.com/world-etc-flacm","trackType":""} Jun 03 08:54:13 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:13 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:13 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.311+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.312+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.312+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.312+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.313+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.313+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=251 volume=100 Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.314+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:13.314+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:13 volumio641120 volumio[1075]: info: ------------------------------ 41ms Jun 03 08:54:23 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:23+08:00" level=trace msg="sent dealer ping" Jun 03 08:54:23 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:23+08:00" level=trace msg="received dealer pong" Jun 03 08:54:36 volumio641120 volumio[1075]: info: Preload queue cleared Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::ClearQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::stop Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::stPlaybackTimer Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::updateTrackBlock Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrackBlock Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::serviceStop Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::serviceStop Jun 03 08:54:36 volumio641120 volumio[1075]: info: [1780448076970] ControllerWebradio::stop Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::clearPlayQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::saveQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::addQueueItems Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::addQueueItems Jun 03 08:54:36 volumio641120 volumio[1075]: info: Preload queue cleared Jun 03 08:54:36 volumio641120 volumio[1075]: info: Adding Item to queue: http://mscp3.live-streams.nl:8360/flac.flac Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jun 03 08:54:36 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:36.973+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:36 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:36.973+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:36 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:36.973+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:36 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:36.974+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::saveQueue Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::updateTrackBlock Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrackBlock Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPlay Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::play index 0 Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::stop Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::play index undefined Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:36 volumio641120 volumio[1075]: info: CoreStateMachine::startPlaybackTimer Jun 03 08:54:36 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:36 volumio641120 volumio[1075]: info: [1780448076984] ControllerWebradio::clearAddPlayTrack Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:36 volumio641120 volumio[1075]: info: Jun 03 08:54:36 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:36 volumio641120 volumio[1075]: info: sendMpdCommand stop took 22 milliseconds Jun 03 08:54:36 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:36 volumio641120 volumio[1075]: info: sendMpdCommand stop took 8 milliseconds Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 08:54:36 volumio641120 volumio[1075]: info: Jun 03 08:54:36 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:36 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:36 volumio641120 volumio[1075]: info: Jun 03 08:54:36 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:36 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:36 volumio641120 volumio[1075]: info: Jun 03 08:54:36 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:36 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:36 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand status took 33 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand clear took 33 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand status took 32 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand status took 31 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand status took 30 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:37 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 08:54:37 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 08:54:37 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:37 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:37 volumio641120 volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.039+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=40 volume=100 Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.039+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=40 volume=100 Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.040+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=40 volume=100 Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.040+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=40 volume=100 Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.040+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.040+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.041+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:37 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:37.041+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 47ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 47ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 45ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 22ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:37 volumio641120 volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 58ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Jun 03 08:54:37 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:37 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:37 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 652ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 651ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 650ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 2 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 08:54:37 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Jun 03 08:54:37 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:37 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 5ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: sendMpdCommand play took 4 milliseconds Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 5ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 4ms Jun 03 08:54:37 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 14 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 03 08:54:38 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 03 08:54:38 volumio641120 volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 89 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 76 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 75 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 74 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 93ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 6 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 5 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 6 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand status took 5 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.520+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.520+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.520+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.520+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.521+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.522+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.522+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.522+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.522+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.523+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.523+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.523+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.523+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.524+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.524+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.524+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.524+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 125ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 112ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 111ms Jun 03 08:54:38 volumio641120 volumio[1075]: error: FusionDsp - WebSocket error: [object Object] Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:38 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:38 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.563+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.564+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.564+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.564+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.564+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.565+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.566+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.564+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.567+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=40 volume=100 Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 77ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 73ms Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.567+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.567+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.568+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:38.569+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:38 volumio641120 volumio[1075]: info: ------------------------------ 74ms Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:38 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:38 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:38 volumio641120 volumio[1075]: info: Jun 03 08:54:38 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:39 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 7ms Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand status took 7 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 6ms Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand status took 6 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 6ms Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand status took 6 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 5ms Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand status took 4 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:39 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: 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":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:39 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: 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":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:39 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:39 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:39 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:39 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:39 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.032+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.032+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.033+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.033+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.033+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.034+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.034+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.034+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.034+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 40ms Jun 03 08:54:39 volumio641120 volumio[1075]: info: ------------------------------ 39ms Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.034+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.035+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.036+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.036+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.036+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.036+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.037+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.037+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.037+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=501 volume=100 Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.038+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.039+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:39 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:39.039+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPlay Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::play index 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::stop Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::stPlaybackTimer Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::updateTrackBlock Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrackBlock Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::serviceStop Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::serviceStop Jun 03 08:54:45 volumio641120 volumio[1075]: info: [1780448085658] ControllerWebradio::stop Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.659+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.660+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.660+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.660+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand stop took 36 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::play index undefined Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::startPlaybackTimer Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: [1780448085696] ControllerWebradio::clearAddPlayTrack Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:45 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:45 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:45 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand status took 6 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand stop took 4 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:45 volumio641120 volumio[1075]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand clear took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:45 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:45 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:45 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:45 volumio641120 volumio[1075]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jun 03 08:54:45 volumio641120 volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 08:54:45 volumio641120 volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 08:54:45 volumio641120 volumio[1075]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.712+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.712+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_STOPPED positionMs=0 volume=100 Jun 03 08:54:45 volumio641120 volumio[1075]: info: ------------------------------ 19ms Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.712+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:45 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:45.713+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:45 volumio641120 volumio[1075]: info: ------------------------------ 15ms Jun 03 08:54:45 volumio641120 volumio[1075]: info: ------------------------------ 14ms Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Jun 03 08:54:45 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:45 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:45 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:45 volumio641120 volumio[1075]: info: ------------------------------ 14ms Jun 03 08:54:46 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Jun 03 08:54:46 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 1011ms Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 1010ms Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 1009ms Jun 03 08:54:46 volumio641120 volumio[1075]: info: Jun 03 08:54:46 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:46 volumio641120 volumio[1075]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 2 milliseconds Jun 03 08:54:46 volumio641120 volumio[1075]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 03 08:54:46 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Jun 03 08:54:46 volumio641120 volumio[1075]: info: Jun 03 08:54:46 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Jun 03 08:54:46 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Jun 03 08:54:46 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:46 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 3ms Jun 03 08:54:46 volumio641120 volumio[1075]: info: sendMpdCommand play took 3 milliseconds Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:46 volumio641120 volumio[1075]: info: ------------------------------ 1ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - ---- read samplerate from file: 192000 Jun 03 08:54:47 volumio641120 volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 18 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 17 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 17 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 17 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: Jun 03 08:54:47 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 5 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 44ms Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.738+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.738+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.738+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.738+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.739+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.739+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.739+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.739+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.739+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.740+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.740+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.741+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.742+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=0 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.742+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.742+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.742+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.743+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 57ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 57ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 57ms Jun 03 08:54:47 volumio641120 volumio[1075]: error: FusionDsp - WebSocket error: [object Object] Jun 03 08:54:47 volumio641120 volumio[1075]: error: FusionDsp - WebSocket error: [object Object] Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 38 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: 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":"flac.flac","artist":null,"album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:47 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:47 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:47 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.780+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.780+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.780+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.780+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.782+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.782+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.782+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.782+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.783+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=54 volume=100 Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.784+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:47.785+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 79ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 76ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 77ms Jun 03 08:54:47 volumio641120 volumio[1075]: info: ------------------------------ 77ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 16ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand status took 3 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 3ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 2ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand status took 1 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces system playlist update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Ignoring MPD Status Update Jun 03 08:54:48 volumio641120 volumio[1075]: info: Jun 03 08:54:48 volumio641120 volumio[1075]: ---------------------------- MPD announces state update: player Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::getState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 1ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand status took 2 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseState Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:48 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:48 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:48 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.332+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.332+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.332+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.332+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.334+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.335+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.336+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.336+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.337+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.337+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.337+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.337+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.338+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.338+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.338+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.339+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.340+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.340+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.340+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.340+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.342+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.342+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=555 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.343+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 39ms Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.343+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 39ms Jun 03 08:54:48 volumio641120 volumio[1075]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: ControllerMpd::parseTrackInfo Jun 03 08:54:48 volumio641120 volumio[1075]: info: ControllerMpd::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::servicePushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":686,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"flac.flac","artist":"Naim Radio","album":null,"uri":"http://mscp3.live-streams.nl:8360/flac.flac","trackType":"flac"} Jun 03 08:54:48 volumio641120 volumio[1075]: verbose: CURRENT POSITION 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState stateService play Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::syncState currentStatus play Jun 03 08:54:48 volumio641120 volumio[1075]: info: Received an update from plugin. extracting info from payload Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CorePlayQueue::getTrack 0 Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreStateMachine::pushState Jun 03 08:54:48 volumio641120 volumio[1075]: info: CoreCommandRouter::volumioPushState Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.362+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=648 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.363+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=648 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.363+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.363+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.364+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" state=STATUS_PLAYING positionMs=648 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.364+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" state=STATUS_PLAYING positionMs=648 volume=100 Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.364+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242,192.168.4.24:49246 @ 0x2b23530" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:54:48.365+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.24:49242 @ 0x2b22990" id=http://mscp3.live-streams.nl:8360/flac.flac title="Naim Radio (FLAC)" Jun 03 08:54:48 volumio641120 volumio[1075]: info: ------------------------------ 55ms Jun 03 08:54:51 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 08:54:51 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 03 08:54:53 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:53+08:00" level=trace msg="received accesspoint ping" Jun 03 08:54:53 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:53+08:00" level=trace msg="received accesspoint pong ack" Jun 03 08:54:53 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:53+08:00" level=trace msg="sent dealer ping" Jun 03 08:54:53 volumio641120 go-librespot[1388]: time="2026-06-03T08:54:53+08:00" level=trace msg="received dealer pong" Jun 03 08:55:01 volumio641120 volumio[1075]: info: Starting Uninstall of plugin audio_interface - mpdoutput Jun 03 08:55:01 volumio641120 volumio[1075]: info: Uninstalling plugin mpdoutput Jun 03 08:55:01 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 03 08:55:01 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:55:01.191+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 03 08:55:01 volumio641120 volumio[1075]: info: Disabling plugin mpdoutput Jun 03 08:55:01 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:55:01.197+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 03 08:55:01 volumio641120 volumio[1075]: info: Checking if uninstall.sh is present Jun 03 08:55:01 volumio641120 sudo[4880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 03 08:55:01 volumio641120 sudo[4880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 08:55:01 volumio641120 volumio[1075]: info: Executing uninstall.sh Jun 03 08:55:01 volumio641120 sudo[4885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/mpdoutput/uninstall.sh Jun 03 08:55:01 volumio641120 sudo[4885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 08:55:01 volumio641120 systemd[1]: Starting Cleanup of Temporary Directories... Jun 03 08:55:01 volumio641120 volumio[1075]: info: Uninstall script completed Jun 03 08:55:01 volumio641120 volumio[1075]: info: Removing plugin mpdoutput from configuration Jun 03 08:55:01 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:55:01.279+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 03 08:55:01 volumio641120 sudo[4885]: pam_unix(sudo:session): session closed for user root Jun 03 08:55:01 volumio641120 systemd[1]: Stopping Music Player Daemon... Jun 03 08:55:01 volumio641120 systemd[1]: mpd.service: Succeeded. Jun 03 08:55:01 volumio641120 systemd[1]: Stopped Music Player Daemon. Jun 03 08:55:01 volumio641120 systemd[1]: Starting Music Player Daemon... Jun 03 08:55:01 volumio641120 volumio[1075]: info: Successfully removed mpdoutput configuration files Jun 03 08:55:01 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:55:01.364+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 03 08:55:01 volumio641120 volumio[1075]: info: Plugin folders cleanup Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning into folder /volumio/app/plugins/ Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category audio_interface Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category miscellanea Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category music_service Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category plugins.json Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category system_controller Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category user_interface Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning into folder /data/plugins/ Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category audio_interface Jun 03 08:55:01 volumio641120 volumio[1075]: info: Cleaning folder for mpdoutput Jun 03 08:55:01 volumio641120 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jun 03 08:55:01 volumio641120 systemd[1]: Started Cleanup of Temporary Directories. Jun 03 08:55:01 volumio641120 sudo[4891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 03 08:55:01 volumio641120 sudo[4891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 03 08:55:01 volumio641120 sudo[4891]: pam_unix(sudo:session): session closed for user root Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category music_service Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category system_controller Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category system_hardware Jun 03 08:55:01 volumio641120 volumio[1075]: info: Scanning category user_interface Jun 03 08:55:01 volumio641120 volumio[1075]: info: Plugin folders cleanup completed Jun 03 08:55:01 volumio641120 volumio5-onboarding[1978]: time=2026-06-03T08:55:01.468+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Jun 03 08:55:01 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 03 08:55:01 volumio641120 volumio[1075]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.4.1","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Backup & Restore Data","name":"backup_restore","category":"system_controller","version":"0.7.4","icon":"fa-floppy-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"ScheduledRestart","name":"scheduledrestart","category":"system_controller","version":"0.7.2","icon":"fa-clock-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Audiophonics ON/OFF","name":"audiophonicsonoff","category":"system_hardware","version":"1.0.5","icon":"fa fa-cogs","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.4","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Now Playing","name":"now_playing","category":"user_interface","version":"0.7.6","icon":"fa-play-circle","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] Jun 03 08:55:01 volumio641120 volumio[1075]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 03 08:55:02 volumio641120 mpd[4893]: Jun 03 08:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 03 08:55:02 volumio641120 systemd[1]: Started Music Player Daemon. Jun 03 08:55:02 volumio641120 sudo[4880]: pam_unix(sudo:session): session closed for user root Jun 03 08:55:02 volumio641120 volumio[1075]: error: updateQueue error: null Jun 03 08:55:05 volumio641120 volumio[1075]: info: Starting Uninstall of plugin audio_interface - fusiondsp Jun 03 08:55:05 volumio641120 volumio[1075]: info: Uninstalling plugin fusiondsp Jun 03 08:55:05 volumio641120 volumio[1075]: info: FusionDsp - Stopping FusionDsp service Jun 03 08:55:05 volumio641120 volumio[1075]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 08:55:05 volumio641120 volumio[1075]: TypeError: Cannot read property 'stop' of null Jun 03 08:55:05 volumio641120 volumio[1075]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) Jun 03 08:55:05 volumio641120 volumio[1075]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 03 08:55:05 volumio641120 volumio[1075]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jun 03 08:55:05 volumio641120 volumio[1075]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jun 03 08:55:05 volumio641120 volumio[1075]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1271:45) Jun 03 08:55:05 volumio641120 volumio[1075]: at Socket.emit (events.js:315:20) Jun 03 08:55:05 volumio641120 volumio[1075]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 03 08:55:05 volumio641120 volumio[1075]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 03 08:55:05 volumio641120 volumio[1075]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 03 08:55:06 volumio641120 sudo[4919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-03 08:54 Jun 03 08:55:06 volumio641120 sudo[4919]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"