May 28 20:17:00 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:00.628Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:05 volumio volumio[1220]: info: Received Get System Info May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:05 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:05 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:05.802Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:05 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:05.802Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:05 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:06 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:06.599Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:12 volumio volumio[1220]: info: Received Get System Info May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:12 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:12 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:12.094Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:12 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:12.094Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:12 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:12 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:12.855Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:18 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 28 20:17:18 volumio volumio[1220]: info: [1779999438794] ControllerWebradio::stop May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 28 20:17:18 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:18 volumio volumio[1220]: info: Adding Item to queue: http://onair15.xdevel.com:7012/1 May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.796Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.796Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.796Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.796Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.radioparadise.com/world-etc-flacm title="Radio Paradise World (FLAC+)" May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:18 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:18 volumio volumio[1220]: info: [1779999438799] ControllerWebradio::clearAddPlayTrack May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:18 volumio volumio[1220]: info: FusionDsp - Volumio is not playing May 28 20:17:18 volumio volumio[1220]: info: FusionDsp - Clipped samples monitor stopped May 28 20:17:18 volumio volumio[1220]: info: May 28 20:17:18 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:18 volumio volumio[1220]: info: sendMpdCommand stop took 10 milliseconds May 28 20:17:18 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:18 volumio volumio[1220]: info: sendMpdCommand stop took 5 milliseconds May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:17:18 volumio volumio[1220]: info: May 28 20:17:18 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:18 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:18 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:18 volumio volumio[1220]: info: sendMpdCommand clear took 1 milliseconds May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://onair15.xdevel.com:7012/1" May 28 20:17:18 volumio volumio[1220]: error: updateQueue error: null May 28 20:17:18 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:18 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:18 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:18 volumio volumio[1220]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 20:17:18 volumio volumio[1220]: info: ------------------------------ 4ms May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.919Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:18 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:18.919Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:18 volumio volumio[1220]: info: Received Get System Info May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:18 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:18 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:18 volumio volumio[1220]: info: camilladsp respawn in 10000 ms (attempt 8/10) May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:18 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand add "http://onair15.xdevel.com:7012/1" May 28 20:17:19 volumio volumio[1220]: info: May 28 20:17:19 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:19 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand add "http://onair15.xdevel.com:7012/1" took 1 milliseconds May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 28 20:17:19 volumio volumio[1220]: info: ------------------------------ 0ms May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand play took 0 milliseconds May 28 20:17:19 volumio volumio[1220]: info: May 28 20:17:19 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:19 volumio volumio[1220]: info: May 28 20:17:19 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand status took 2 milliseconds May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:19 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} May 28 20:17:19 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 28 20:17:19 volumio volumio[1220]: info: ------------------------------ 4ms May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:19 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1","artist":null,"album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} May 28 20:17:19 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:19 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.329Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.329Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.329Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.329Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.329Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.330Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.330Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio[1220]: info: ------------------------------ 6ms May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.330Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:19 volumio volumio[1220]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:19 volumio volumio[1220]: info: May 28 20:17:19 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:19 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:19 volumio volumio[1220]: info: May 28 20:17:19 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:19 volumio volumio[1220]: info: ------------------------------ 2ms May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand status took 0 milliseconds May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:19 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:17:19 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:19 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:19 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1","artist":"RadioRload.it ?","album":null,"uri":"http://onair15.xdevel.com:7012/1","trackType":"com:7012/1"} May 28 20:17:19 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:19 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:19 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:19 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:19 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.510Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=188 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.510Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=188 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.510Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.510Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio[1220]: info: ------------------------------ 13ms May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.515Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=188 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.515Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=188 volume=39 May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.515Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.515Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - ---- read samplerate from file: 352800 May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:19 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:19.680Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Reload WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:19 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:19 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:20 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:20 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:20 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:20 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:22 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:22 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:22 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:22 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:22 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:22 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:22 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:22 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:25 volumio volumio[1220]: info: Received Get System Info May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:25 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:25 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:25.059Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:25 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:25.059Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:25 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:26 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:26.006Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:27 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 28 20:17:27 volumio volumio[1220]: info: [1779999447590] ControllerWebradio::stop May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 28 20:17:27 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:27 volumio volumio[1220]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsc_de/aacp_96 May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:17:27 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:27.592Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:27 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:27.593Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:27 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:27.593Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:27 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:27.593Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://onair15.xdevel.com:7012/1 title="Radio Reload" May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:27 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:27 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 28 20:17:27 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:27 volumio volumio[1220]: info: [1779999447598] ControllerWebradio::clearAddPlayTrack May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:27 volumio volumio[1220]: info: FusionDsp - Volumio is not playing May 28 20:17:27 volumio volumio[1220]: info: FusionDsp - Clipped samples monitor stopped May 28 20:17:27 volumio volumio[1220]: info: May 28 20:17:27 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:27 volumio volumio[1220]: info: sendMpdCommand stop took 10 milliseconds May 28 20:17:27 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:27 volumio volumio[1220]: info: sendMpdCommand stop took 2 milliseconds May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:17:27 volumio volumio[1220]: info: May 28 20:17:27 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:27 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:27 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:27 volumio volumio[1220]: info: sendMpdCommand clear took 0 milliseconds May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" May 28 20:17:27 volumio volumio[1220]: error: updateQueue error: null May 28 20:17:27 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:27 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:27 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:27 volumio volumio[1220]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 20:17:27 volumio volumio[1220]: info: ------------------------------ 3ms May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsc_de/aacp_96" took 0 milliseconds May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 28 20:17:28 volumio volumio[1220]: info: May 28 20:17:28 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:28 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand play took 1 milliseconds May 28 20:17:28 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:28 volumio volumio[1220]: info: May 28 20:17:28 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:28 volumio volumio[1220]: info: May 28 20:17:28 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand status took 2 milliseconds May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:28 volumio volumio[1220]: info: May 28 20:17:28 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:28 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:28 volumio volumio[1220]: info: May 28 20:17:28 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand status took 5 milliseconds May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:28 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:28 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Cipriani Potter - Sinfonie Nr.1 g-Moll","artist":"Swiss Classic D","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 28 20:17:28 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 28 20:17:28 volumio volumio[1220]: info: ------------------------------ 11ms May 28 20:17:28 volumio volumio[1220]: info: ------------------------------ 5ms May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand status took 4 milliseconds May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 4 milliseconds May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:28 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:28 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"95 Kbps","isStreaming":false,"title":"Cipriani Potter - Sinfonie Nr.1 g-Moll","artist":"Swiss Classic D","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 28 20:17:28 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:28 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.969Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.969Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.969Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.969Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.970Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.971Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.971Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.971Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio[1220]: info: ------------------------------ 17ms May 28 20:17:28 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 8 milliseconds May 28 20:17:28 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:28 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:28 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:28 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"95 Kbps","isStreaming":false,"title":"Cipriani Potter - Sinfonie Nr.1 g-Moll","artist":"Swiss Classic D","album":null,"uri":"http://stream.srg-ssr.ch/m/rsc_de/aacp_96","trackType":""} May 28 20:17:28 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:28 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:28 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:28 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.979Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=20 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=20 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=20 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=20 volume=39 May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:28.980Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Cipriani Potter - Sinfonie Nr.1 g-Moll" May 28 20:17:28 volumio volumio[1220]: info: ------------------------------ 22ms May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:28 volumio volumio[1220]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:28 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:29 volumio volumio[1220]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 28 20:17:29 volumio volumio[1220]: info: FusionDsp - ---- read samplerate from file: 352800 May 28 20:17:29 volumio volumio[1220]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 28 20:17:31 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:31.098Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:31 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:31.099Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:31 volumio volumio[1220]: info: Received Get System Info May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:31 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:31 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:32 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:32.044Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:36 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 28 20:17:36 volumio volumio[1220]: info: [1779999456844] ControllerWebradio::stop May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 28 20:17:36 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:36 volumio volumio[1220]: info: Adding Item to queue: http://stream.srg-ssr.ch/m/rsj/aacp_96 May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:17:36 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:36.847Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:36 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:36.849Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:36 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:36.849Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Radio Swiss Classic" May 28 20:17:36 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:36.849Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsc_de/aacp_96 title="Radio Swiss Classic" May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:36 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:36 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 28 20:17:36 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:36 volumio volumio[1220]: info: [1779999456853] ControllerWebradio::clearAddPlayTrack May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:36 volumio volumio[1220]: info: FusionDsp - Volumio is not playing May 28 20:17:36 volumio volumio[1220]: info: FusionDsp - Clipped samples monitor stopped May 28 20:17:36 volumio volumio[1220]: info: May 28 20:17:36 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:36 volumio volumio[1220]: info: sendMpdCommand stop took 12 milliseconds May 28 20:17:36 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:36 volumio volumio[1220]: info: sendMpdCommand stop took 3 milliseconds May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:17:36 volumio volumio[1220]: info: May 28 20:17:36 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:36 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:36 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:36 volumio volumio[1220]: info: sendMpdCommand clear took 1 milliseconds May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://stream.srg-ssr.ch/m/rsj/aacp_96" May 28 20:17:36 volumio volumio[1220]: error: updateQueue error: null May 28 20:17:36 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:36 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:36 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:36 volumio volumio[1220]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 20:17:36 volumio volumio[1220]: info: ------------------------------ 3ms May 28 20:17:36 volumio volumio[1220]: info: camilladsp respawn in 10000 ms (attempt 9/10) May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand add "http://stream.srg-ssr.ch/m/rsj/aacp_96" took 0 milliseconds May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 28 20:17:37 volumio volumio[1220]: info: May 28 20:17:37 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:37 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand play took 2 milliseconds May 28 20:17:37 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:37 volumio volumio[1220]: info: May 28 20:17:37 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:37 volumio volumio[1220]: info: May 28 20:17:37 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand status took 2 milliseconds May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:37 volumio volumio[1220]: info: May 28 20:17:37 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:37 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:37 volumio volumio[1220]: info: May 28 20:17:37 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:37 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:37 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} May 28 20:17:37 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 28 20:17:37 volumio volumio[1220]: info: ------------------------------ 5ms May 28 20:17:37 volumio volumio[1220]: info: ------------------------------ 6ms May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand status took 6 milliseconds May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 6 milliseconds May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:37 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:37 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} May 28 20:17:37 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:37 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:37 volumio volumio[1220]: info: ------------------------------ 14ms May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.976Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.976Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.976Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.976Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.977Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.977Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.977Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.977Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 7 milliseconds May 28 20:17:37 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:37 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:37 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:37 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} May 28 20:17:37 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:37 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:37 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:37 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.982Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.985Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.985Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.985Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.986Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.986Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.986Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:37.986Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Michel Weber Mike Goetz Swingtet - Serenade For A Trench Coat" May 28 20:17:37 volumio volumio[1220]: info: ------------------------------ 24ms May 28 20:17:37 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:37 volumio volumio[1220]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 20:17:37 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:37 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:37 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:38 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:38 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:38 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:38 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:38 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:38.109Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:38 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:38.110Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:38 volumio volumio[1220]: info: Received Get System Info May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:38 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:38 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - ---- read samplerate from file: 352800 May 28 20:17:38 volumio volumio[1220]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 28 20:17:38 volumio volumio[1220]: error: FusionDsp - Reload WebSocket error: [object Object] May 28 20:17:38 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:38.884Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:44 volumio volumio[1220]: info: Received Get System Info May 28 20:17:44 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:44.216Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:44 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:44.216Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:44 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:44 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.045Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:45 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 28 20:17:45 volumio volumio[1220]: info: [1779999465106] ControllerWebradio::stop May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 28 20:17:45 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:45 volumio volumio[1220]: info: Adding Item to queue: http://media-sov.musicradio.com:80/RadioXLondonMP3 May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.108Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.108Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.108Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.109Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://stream.srg-ssr.ch/m/rsj/aacp_96 title="Radio Swiss Jazz" May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: info: [1779999465111] ControllerWebradio::clearAddPlayTrack May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Volumio is not playing May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Clipped samples monitor stopped May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand stop took 8 milliseconds May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand stop took 3 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:45 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand clear took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://media-sov.musicradio.com:80/RadioXLondonMP3" May 28 20:17:45 volumio volumio[1220]: error: updateQueue error: null May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:45 volumio volumio[1220]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 3ms May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand add "http://media-sov.musicradio.com:80/RadioXLondonMP3" took 0 milliseconds May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:45 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand play took 2 milliseconds May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand status took 2 milliseconds May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RadioXLondonMP3","artist":null,"album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} May 28 20:17:45 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 4ms May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"RadioXLondonMP3","artist":null,"album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} May 28 20:17:45 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:45 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.630Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.630Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.630Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.631Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.632Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 9ms May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.632Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.633Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.633Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:45 volumio volumio[1220]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:45 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:45 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:45 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:45 volumio volumio[1220]: info: May 28 20:17:45 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 56ms May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand status took 51 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:45 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 11 milliseconds May 28 20:17:45 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:45 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:45 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:45 volumio volumio[1220]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Faithless - Insomnia","artist":"Radio X London","album":null,"uri":"http://media-sov.musicradio.com:80/RadioXLondonMP3","trackType":""} May 28 20:17:45 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:45 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:45 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:45 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.826Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=178 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.826Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=178 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.826Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Faithless - Insomnia" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.827Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Faithless - Insomnia" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.829Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=178 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.829Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=178 volume=39 May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.829Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Faithless - Insomnia" May 28 20:17:45 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:45.829Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Faithless - Insomnia" May 28 20:17:45 volumio volumio[1220]: info: ------------------------------ 76ms May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - ---- read samplerate from file: 352800 May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:45 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:45 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:45 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:46 volumio volumio[1220]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 28 20:17:46 volumio volumio[1220]: error: FusionDsp - Reload WebSocket error: [object Object] May 28 20:17:49 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:49 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:17:50 volumio volumio[1220]: info: Received Get System Info May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:50 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:50 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:50.361Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:50 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:50.361Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:50 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:51 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:51.186Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:17:54 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::ClearQueue May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::stPlaybackTimer May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::serviceStop May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::serviceStop May 28 20:17:54 volumio volumio[1220]: info: [1779999474167] ControllerWebradio::stop May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::clearPlayQueue May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::addQueueItems May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::addQueueItems May 28 20:17:54 volumio volumio[1220]: info: Preload queue cleared May 28 20:17:54 volumio volumio[1220]: info: Adding Item to queue: http://listen.jazz88.org/ksds.mp3 May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 28 20:17:54 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:54.169Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:54 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:54.169Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_STOPPED positionMs=0 volume=39 May 28 20:17:54 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:54.169Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:54 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:54.169Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://media-sov.musicradio.com:80/RadioXLondonMP3 title="Radio X London" May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::volumioPushQueue May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::saveQueue May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::updateTrackBlock May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrackBlock May 28 20:17:54 volumio volumio[1220]: info: CoreCommandRouter::volumioPlay May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::play index 0 May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::stop May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::play index undefined May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:54 volumio volumio[1220]: info: CoreStateMachine::startPlaybackTimer May 28 20:17:54 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:54 volumio volumio[1220]: info: [1779999474173] ControllerWebradio::clearAddPlayTrack May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand stop May 28 20:17:54 volumio volumio[1220]: info: FusionDsp - Volumio is not playing May 28 20:17:54 volumio volumio[1220]: info: FusionDsp - Clipped samples monitor stopped May 28 20:17:54 volumio volumio[1220]: info: May 28 20:17:54 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:54 volumio volumio[1220]: info: sendMpdCommand stop took 10 milliseconds May 28 20:17:54 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:54 volumio volumio[1220]: info: sendMpdCommand stop took 4 milliseconds May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand clear May 28 20:17:54 volumio volumio[1220]: info: May 28 20:17:54 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:54 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:54 volumio volumio[1220]: info: sendMpdCommand status took 0 milliseconds May 28 20:17:54 volumio volumio[1220]: info: sendMpdCommand clear took 0 milliseconds May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand load "http://listen.jazz88.org/ksds.mp3" May 28 20:17:54 volumio volumio[1220]: error: updateQueue error: null May 28 20:17:54 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:54 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 1 milliseconds May 28 20:17:54 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:54 volumio volumio[1220]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 28 20:17:54 volumio volumio[1220]: info: ------------------------------ 4ms May 28 20:17:54 volumio volumio[1220]: info: camilladsp respawn in 10000 ms (attempt 10/10) May 28 20:17:55 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" May 28 20:17:55 volumio volumio[1220]: info: sendMpdCommand add "http://listen.jazz88.org/ksds.mp3" took 0 milliseconds May 28 20:17:55 volumio volumio[1220]: info: CoreStateMachine::setConsumeUpdateService mpd May 28 20:17:55 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand play May 28 20:17:55 volumio volumio[1220]: info: May 28 20:17:55 volumio volumio[1220]: ---------------------------- MPD announces system playlist update May 28 20:17:55 volumio volumio[1220]: info: Ignoring MPD Status Update May 28 20:17:55 volumio volumio[1220]: info: sendMpdCommand play took 1 milliseconds May 28 20:17:55 volumio volumio[1220]: info: ------------------------------ 1ms May 28 20:17:56 volumio volumio[1220]: info: May 28 20:17:56 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:56 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:56 volumio volumio[1220]: info: sendMpdCommand status took 2 milliseconds May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:56 volumio volumio[1220]: info: May 28 20:17:56 volumio volumio[1220]: ---------------------------- MPD announces state update: player May 28 20:17:56 volumio volumio[1220]: info: ControllerMpd::getState May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand status May 28 20:17:56 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:56 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:56 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:56 volumio volumio[1220]: 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":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 28 20:17:56 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus stop May 28 20:17:56 volumio volumio[1220]: info: ------------------------------ 4ms May 28 20:17:56 volumio volumio[1220]: info: sendMpdCommand status took 1 milliseconds May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::parseState May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 20:17:56 volumio volumio[1220]: info: sendMpdCommand playlistinfo took 0 milliseconds May 28 20:17:56 volumio volumio[1220]: verbose: ControllerMpd::parseTrackInfo May 28 20:17:56 volumio volumio[1220]: info: ControllerMpd::pushState May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::servicePushState May 28 20:17:56 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:56 volumio volumio[1220]: 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":"ksds.mp3","artist":null,"album":null,"uri":"http://listen.jazz88.org/ksds.mp3","trackType":"mp3"} May 28 20:17:56 volumio volumio[1220]: verbose: CURRENT POSITION 0 May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::syncState stateService play May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::syncState currentStatus play May 28 20:17:56 volumio volumio[1220]: info: Received an update from plugin. extracting info from payload May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:56 volumio volumio[1220]: info: CorePlayQueue::getTrack 0 May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:56 volumio volumio[1220]: info: CoreStateMachine::pushState May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::volumioPushState May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.350Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.350Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" state=STATUS_PLAYING positionMs=0 volume=39 May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://listen.jazz88.org/ksds.mp3 title="San Diego Jazz 88.3" May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" id=http://listen.jazz88.org/ksds.mp3 title="San Diego Jazz 88.3" May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://listen.jazz88.org/ksds.mp3 title="San Diego Jazz 88.3" May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.351Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" id=http://listen.jazz88.org/ksds.mp3 title="San Diego Jazz 88.3" May 28 20:17:56 volumio volumio[1220]: info: ------------------------------ 6ms May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:56 volumio volumio[1220]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - Volumio is playing May 28 20:17:56 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:56 volumio volumio[1220]: error: FusionDsp - Monitor WebSocket error: [object Object] May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 28 20:17:56 volumio volumio[1220]: info: Received Get System Info May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.390Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:56 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:56.390Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:17:56 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:17:56 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - ---- read samplerate, raw: 352800,S32_LE,2,32 May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - ---- read samplerate from file: 352800 May 28 20:17:56 volumio volumio[1220]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 28 20:17:56 volumio volumio[1220]: error: FusionDsp - Reload WebSocket error: [object Object] May 28 20:17:57 volumio volumio5-onboarding[2159]: time=2026-05-28T20:17:57.315Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:18:02 volumio volumio[1220]: info: Received Get System Info May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:18:02 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:18:02 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:02.435Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:18:02 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:02.435Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:18:02 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:18:03 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:03.362Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:18:03 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 20:18:08 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:18:08 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:18:08 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:18:08 volumio volumio[1220]: info: FusionDsp - Clipping Monitor started May 28 20:18:08 volumio volumio[1220]: info: Received Get System Info May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 28 20:18:08 volumio volumio[1220]: info: Discovery: Getting this device information May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::volumioGetState May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 28 20:18:08 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:08.574Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.100:57720 @ 0xc0004e9b00" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:18:08 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:08.575Z level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%01,192.168.1.100:57720 @ 0xc00036f590" available=true connected=true macAddress=c4:85:08:10:c1:d3 ip4Address=192.168.1.115/24 ip6Address= ssid=GKM2 May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 28 20:18:08 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 28 20:18:09 volumio volumio5-onboarding[2159]: time=2026-05-28T20:18:09.466Z level=INFO msg="service successfully established" component=discovery/localnet May 28 20:18:09 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 20:18:09 volumio volumio[1220]: info: TuneIn handleBrowseUri: tunein/popular May 28 20:18:09 volumio volumio[1220]: info: Preload queue cleared May 28 20:18:13 volumio volumio[1220]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 28 20:18:13 volumio volumio[1220]: info: Getting BBC Radios May 28 20:18:13 volumio volumio[1220]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:18:13 volumio volumio[1220]: Error May 28 20:18:13 volumio volumio[1220]: at /volumio/app/plugins/music_service/webradio/index.js:427:31 May 28 20:18:13 volumio volumio[1220]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:444:15) May 28 20:18:13 volumio volumio[1220]: at self.callback (/volumio/node_modules/request/request.js:185:22) May 28 20:18:13 volumio volumio[1220]: at Request.emit (node:events:514:28) May 28 20:18:13 volumio volumio[1220]: at Request.onRequestError (/volumio/node_modules/request/request.js:877:8) May 28 20:18:13 volumio volumio[1220]: at ClientRequest.emit (node:events:514:28) May 28 20:18:13 volumio volumio[1220]: at Socket.socketErrorListener (node:_http_client:495:9) May 28 20:18:13 volumio volumio[1220]: at Socket.emit (node:events:514:28) May 28 20:18:13 volumio volumio[1220]: at emitErrorNT (node:internal/streams/destroy:151:8) May 28 20:18:13 volumio volumio[1220]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) May 28 20:18:13 volumio volumio[1220]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) May 28 20:18:13 volumio volumio[1220]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 20:18:13 volumio sudo[8427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 20:17' May 28 20:18:13 volumio sudo[8427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"