May 27 21:11:05 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:11:05 volumio volumio[1233]: info: [1779901865269] ControllerWebradio::stop May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:11:05 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:05 volumio volumio[1233]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s1999 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.279+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.279+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://cp11.serverse.com:2199/tunein/hgsmgluv.pls title="American Tamil Radio" May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:05 volumio volumio[1233]: info: May 27 21:11:05 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:05 volumio volumio[1233]: info: sendMpdCommand stop took 31 milliseconds May 27 21:11:05 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:05 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:05 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:05 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Shakthisri Gopalan - Angnyaade Ang","artist":null,"album":null,"uri":"http://142.132.250.174:7188/stream","trackType":""} May 27 21:11:05 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:05 volumio volumio[1233]: info: No code May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.318+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.318+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.318+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id= title= May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.318+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id= title= May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.319+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:05.319+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id= title= May 27 21:11:05 volumio volumio[1233]: info: ------------------------------ 22ms May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:05 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:11:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:05 volumio volumio[1233]: info: [1779901865654] ControllerWebradio::clearAddPlayTrack May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:05 volumio volumio[1233]: info: sendMpdCommand stop took 1 milliseconds May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear May 27 21:11:05 volumio volumio[1233]: info: May 27 21:11:05 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:05 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:05 volumio volumio[1233]: info: sendMpdCommand clear took 1 milliseconds May 27 21:11:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "http://ctbcfmradio.primcast.com:4086" May 27 21:11:05 volumio volumio[1233]: error: updateQueue error: null May 27 21:11:05 volumio volumio[1233]: info: ------------------------------ 1ms May 27 21:11:06 volumio volumio[1233]: info: camilladsp respawn in 200 ms (attempt 2/10) May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "http://ctbcfmradio.primcast.com:4086" May 27 21:11:09 volumio volumio[1233]: info: May 27 21:11:09 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:09 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand add "http://ctbcfmradio.primcast.com:4086" took 1 milliseconds May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play May 27 21:11:09 volumio volumio[1233]: info: ------------------------------ 2ms May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand play took 1 milliseconds May 27 21:11:09 volumio volumio[1233]: info: May 27 21:11:09 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:09 volumio volumio[1233]: info: May 27 21:11:09 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand status took 4 milliseconds May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand status took 1 milliseconds May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:09 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ctbcfmradio.primcast.com:4086","artist":null,"album":null,"uri":"http://ctbcfmradio.primcast.com:4086","trackType":"com:4086"} May 27 21:11:09 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:11:09 volumio volumio[1233]: info: ------------------------------ 9ms May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:09 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ctbcfmradio.primcast.com:4086","artist":null,"album":null,"uri":"http://ctbcfmradio.primcast.com:4086","trackType":"com:4086"} May 27 21:11:09 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:09 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.539+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.539+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://ctbcfmradio.primcast.com:4086 title="Canadian Tamil Broadcasting Corporation" May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.540+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.540+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://ctbcfmradio.primcast.com:4086 title="Canadian Tamil Broadcasting Corporation" May 27 21:11:09 volumio volumio[1233]: info: ------------------------------ 16ms May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:09 volumio volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:09 volumio volumio[1233]: info: May 27 21:11:09 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:09 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:09 volumio volumio[1233]: info: May 27 21:11:09 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:09 volumio volumio[1233]: info: ------------------------------ 4ms May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:09 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 0 milliseconds May 27 21:11:09 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:09 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:09 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1020,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ctbcfmradio.primcast.com:4086","artist":"No Name","album":null,"uri":"http://ctbcfmradio.primcast.com:4086","trackType":"com:4086"} May 27 21:11:09 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:09 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.723+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=251 volume=100 May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.724+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://ctbcfmradio.primcast.com:4086 title="Canadian Tamil Broadcasting Corporation" May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.724+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=251 volume=100 May 27 21:11:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:09.724+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://ctbcfmradio.primcast.com:4086 title="Canadian Tamil Broadcasting Corporation" May 27 21:11:09 volumio volumio[1233]: info: ------------------------------ 14ms May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - ---- read samplerate from file: 48000 May 27 21:11:09 volumio volumio[1233]: info: camilladsp stopping service pid 3316... May 27 21:11:09 volumio volumio[1233]: grep: /proc/3316/cmdline: binary file matches May 27 21:11:09 volumio volumio[1233]: grep: /proc/3316/cmdline: binary file matches May 27 21:11:09 volumio volumio[1233]: info: camilladsp service terminated, instance 1 May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:11:09 volumio volumio[1233]: info: camilladsp service started and running in background, instance 1 May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms May 27 21:11:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 16000ms May 27 21:11:09 volumio volumio[1233]: info: camilladsp respawn in 100 ms (attempt 1/10) May 27 21:11:17 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:18 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:11:18 volumio volumio[1233]: info: [1779901878963] ControllerWebradio::stop May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:11:18 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:18 volumio volumio[1233]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553752 May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 21:11:18 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:18.969+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:18 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:18.969+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://ctbcfmradio.primcast.com:4086 title="Canadian Tamil Broadcasting Corporation" May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:18 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:18 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:11:18 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:18 volumio volumio[1233]: info: [1779901878978] ControllerWebradio::clearAddPlayTrack May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:18 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:18 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:18 volumio volumio[1233]: info: May 27 21:11:18 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:18 volumio volumio[1233]: info: sendMpdCommand stop took 18 milliseconds May 27 21:11:18 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:18 volumio volumio[1233]: info: sendMpdCommand stop took 5 milliseconds May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear May 27 21:11:18 volumio volumio[1233]: info: May 27 21:11:18 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:18 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:18 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:18 volumio volumio[1233]: info: sendMpdCommand clear took 2 milliseconds May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553752" May 27 21:11:18 volumio volumio[1233]: error: updateQueue error: null May 27 21:11:18 volumio volumio[1233]: info: ------------------------------ 3ms May 27 21:11:18 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:18 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:18 volumio volumio[1233]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 21:11:18 volumio volumio[1233]: info: ------------------------------ 7ms May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553752" took 395 milliseconds May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:19 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand play took 1 milliseconds May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 1ms May 27 21:11:19 volumio volumio[1233]: info: camilladsp respawn in 200 ms (attempt 2/10) May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand status took 1 milliseconds May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:19 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:19 volumio volumio[1233]: 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":"ind","artist":"(#1 - 90/9999) Tamilpanpalai Universe","album":null,"uri":"http://167.114.174.204:8140/ind","trackType":""} May 27 21:11:19 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 9ms May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:19 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:19 volumio volumio[1233]: 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":"ind","artist":"(#1 - 90/9999) Tamilpanpalai Universe","album":null,"uri":"http://167.114.174.204:8140/ind","trackType":""} May 27 21:11:19 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:19 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.881+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.882+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Tamilpanpalai Universe" May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.883+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.884+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Tamilpanpalai Universe" May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 19ms May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:19 volumio volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:19 volumio volumio[1233]: error: FusionDsp - Monitor WebSocket error: [object Object] May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:19 volumio volumio[1233]: error: FusionDsp - Monitor WebSocket error: [object Object] May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:19 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 2ms May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:19 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:19 volumio volumio[1233]: info: May 27 21:11:19 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 5ms May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:19 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:19 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":435,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]","artist":"Tamilpanpalai Universe","album":null,"uri":"http://167.114.174.204:8140/ind","trackType":""} May 27 21:11:19 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:19 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.969+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.969+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]" May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.971+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.971+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]" May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 19ms May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand status took 11 milliseconds May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:19 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 21:11:19 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:19 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:19 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:19 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1354,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"64 Kbps","isStreaming":false,"title":"Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]","artist":"Tamilpanpalai Universe","album":null,"uri":"http://167.114.174.204:8140/ind","trackType":""} May 27 21:11:19 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:19 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:19 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.987+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=252 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.987+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]" May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.989+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=252 volume=100 May 27 21:11:19 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:19.989+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=http://167.114.174.204:8140/ind title="Unknown - குட்டி ஆடு | Kutti Aadu | Pasavalai | C. S. Jayaraman | Viswanathan–Ramamoorthy | Kalyanasundaram [1iZL]" May 27 21:11:19 volumio volumio[1233]: info: ------------------------------ 32ms May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:19 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 21:11:20 volumio volumio[1233]: info: camilladsp stopping service pid 3387... May 27 21:11:20 volumio volumio[1233]: grep: /proc/3387/cmdline: binary file matches May 27 21:11:20 volumio volumio[1233]: grep: /proc/3387/cmdline: binary file matches May 27 21:11:20 volumio volumio[1233]: grep: /proc/3387/cmdline: binary file matches May 27 21:11:20 volumio volumio[1233]: grep: /proc/3387/cmdline: binary file matches May 27 21:11:20 volumio volumio[1233]: grep: /proc/3387/cmdline: binary file matches May 27 21:11:20 volumio volumio[1233]: info: camilladsp service terminated, instance 1 May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:11:20 volumio volumio[1233]: info: camilladsp service started and running in background, instance 1 May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:20 volumio volumio[1233]: info: FusionDsp - Clipping Monitor reconnecting in 30000ms May 27 21:11:20 volumio volumio[1233]: info: camilladsp respawn in 100 ms (attempt 1/10) May 27 21:11:25 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:29 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:11:29 volumio volumio[1233]: info: [1779901889909] ControllerWebradio::stop May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:11:29 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:29 volumio volumio[1233]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540300 May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 21:11:29 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:29.910+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:29 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:29.911+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99553752" title="Tamilpanpalai Universe" May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:29 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:29 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:11:29 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:29 volumio volumio[1233]: info: [1779901889928] ControllerWebradio::clearAddPlayTrack May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:29 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:29 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:29 volumio volumio[1233]: info: May 27 21:11:29 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:29 volumio volumio[1233]: info: sendMpdCommand stop took 25 milliseconds May 27 21:11:29 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:29 volumio volumio[1233]: info: sendMpdCommand stop took 6 milliseconds May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear May 27 21:11:29 volumio volumio[1233]: info: May 27 21:11:29 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:29 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:29 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:29 volumio volumio[1233]: info: sendMpdCommand clear took 2 milliseconds May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540300" May 27 21:11:29 volumio volumio[1233]: error: updateQueue error: null May 27 21:11:29 volumio volumio[1233]: info: ------------------------------ 4ms May 27 21:11:29 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 21:11:29 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:29 volumio volumio[1233]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 21:11:29 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:11:30 volumio volumio[1233]: info: May 27 21:11:30 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:30 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:30 volumio volumio[1233]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540300" took 428 milliseconds May 27 21:11:30 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 21:11:30 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play May 27 21:11:30 volumio volumio[1233]: info: ------------------------------ 2ms May 27 21:11:30 volumio volumio[1233]: info: sendMpdCommand play took 1 milliseconds May 27 21:11:30 volumio volumio[1233]: info: camilladsp respawn in 200 ms (attempt 2/10) May 27 21:11:31 volumio volumio[1233]: info: May 27 21:11:31 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand status took 1 milliseconds May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:31 volumio volumio[1233]: info: May 27 21:11:31 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 4 milliseconds May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:31 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 6/99999) Vanavil Hits Radio","album":null,"uri":"https://s7.yesstreaming.net:8094/stream","trackType":""} May 27 21:11:31 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:11:31 volumio volumio[1233]: info: ------------------------------ 15ms May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:31 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 6/99999) Vanavil Hits Radio","album":null,"uri":"https://s7.yesstreaming.net:8094/stream","trackType":""} May 27 21:11:31 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:31 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.745+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.746+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8094/stream title="Vanavil Hits Radio" May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.746+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.746+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8094/stream title="Vanavil Hits Radio" May 27 21:11:31 volumio volumio[1233]: info: ------------------------------ 24ms May 27 21:11:31 volumio volumio[1233]: info: May 27 21:11:31 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:31 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:31 volumio volumio[1233]: info: May 27 21:11:31 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:31 volumio volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:31 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand status took 7 milliseconds May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:31 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 21:11:31 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:31 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:31 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:31 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1477,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"63 Kbps","isStreaming":false,"title":"MAZHAI - VAARUM POOTHU","artist":"Vanavil Hits Radio","album":null,"uri":"https://s7.yesstreaming.net:8094/stream","trackType":""} May 27 21:11:31 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:31 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:31 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:31 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.772+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.772+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8094/stream title="MAZHAI - VAARUM POOTHU" May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.773+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:11:31 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:31.774+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8094/stream title="MAZHAI - VAARUM POOTHU" May 27 21:11:31 volumio volumio[1233]: info: ------------------------------ 24ms May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 21:11:31 volumio volumio[1233]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 21:11:32 volumio volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:11:39 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:11:39 volumio volumio[1233]: info: [1779901899916] ControllerWebradio::stop May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:11:39 volumio volumio[1233]: info: Preload queue cleared May 27 21:11:39 volumio volumio[1233]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516551 May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 27 21:11:39 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:39.918+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:11:39 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:39.918+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99540300" title="Vanavil Hits Radio" May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:11:39 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:39 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:11:39 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:39 volumio volumio[1233]: info: [1779901899923] ControllerWebradio::clearAddPlayTrack May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:11:39 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:11:39 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:11:39 volumio volumio[1233]: info: May 27 21:11:39 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:39 volumio volumio[1233]: info: sendMpdCommand stop took 19 milliseconds May 27 21:11:39 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:39 volumio volumio[1233]: info: sendMpdCommand stop took 7 milliseconds May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear May 27 21:11:39 volumio volumio[1233]: info: May 27 21:11:39 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:39 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:39 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds May 27 21:11:39 volumio volumio[1233]: info: sendMpdCommand clear took 1 milliseconds May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516551" May 27 21:11:39 volumio volumio[1233]: error: updateQueue error: null May 27 21:11:39 volumio volumio[1233]: info: ------------------------------ 4ms May 27 21:11:39 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:11:39 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:39 volumio volumio[1233]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 21:11:39 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:11:40 volumio volumio[1233]: info: May 27 21:11:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:40 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:40 volumio volumio[1233]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516551" took 186 milliseconds May 27 21:11:40 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd May 27 21:11:40 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play May 27 21:11:40 volumio volumio[1233]: info: ------------------------------ 2ms May 27 21:11:40 volumio volumio[1233]: info: sendMpdCommand play took 2 milliseconds May 27 21:11:40 volumio volumio[1233]: info: camilladsp respawn in 400 ms (attempt 3/10) May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:41 volumio volumio[1233]: 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":"stream","artist":"(#1 - 6/1000) Ar Rahman Radio","album":null,"uri":"https://s7.yesstreaming.net:8038/stream","trackType":""} May 27 21:11:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 6ms May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:41 volumio volumio[1233]: 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":"stream","artist":"(#1 - 6/1000) Ar Rahman Radio","album":null,"uri":"https://s7.yesstreaming.net:8038/stream","trackType":""} May 27 21:11:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:41 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.396+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=195 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.396+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="Ar Rahman Radio" May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.397+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=195 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.397+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="Ar Rahman Radio" May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 14ms May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:41 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:11:41 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:11:41 volumio volumio[1233]: info: May 27 21:11:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand status took 6 milliseconds May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand status took 6 milliseconds May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1103,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"En Swasa Kaatrea - Thirakkaatha","artist":"Ar Rahman Radio","album":null,"uri":"https://s7.yesstreaming.net:8038/stream","trackType":""} May 27 21:11:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:41 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.637+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=250 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.638+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="En Swasa Kaatrea - Thirakkaatha" May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.639+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=250 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.640+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="En Swasa Kaatrea - Thirakkaatha" May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 25ms May 27 21:11:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 11 milliseconds May 27 21:11:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:11:41 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:11:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:11:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1481,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"En Swasa Kaatrea - Thirakkaatha","artist":"Ar Rahman Radio","album":null,"uri":"https://s7.yesstreaming.net:8038/stream","trackType":""} May 27 21:11:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:11:41 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:11:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.647+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=446 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.647+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=446 volume=100 May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.648+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="En Swasa Kaatrea - Thirakkaatha" May 27 21:11:41 volumio volumio5-onboarding[1694]: time=2026-05-27T21:11:41.648+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=https://s7.yesstreaming.net:8038/stream title="En Swasa Kaatrea - Thirakkaatha" May 27 21:11:41 volumio volumio[1233]: info: ------------------------------ 30ms May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:11:41 volumio volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:12:00 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: motherearthradio , handleBrowseUri May 27 21:12:00 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:05 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:12:05 volumio volumio[1233]: info: [1779901925708] ControllerWebradio::stop May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:12:05 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:05 volumio volumio[1233]: info: Adding Item to queue: motherearthradio/radio/flac192 May 27 21:12:05 volumio volumio[1233]: info: Exploding uri motherearthradio/radio/flac192 in service motherearthradio May 27 21:12:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:05.711+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:05 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:05.711+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id="http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99516551" title="Ar Rahman Radio" May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:05 volumio volumio[1233]: info: [MER] ▶️ Playing: https://stream.motherearthradio.de/listen/motherearth/motherearth May 27 21:12:05 volumio volumio[1233]: info: [MER] 🔌 Starting SSE for Radio May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:12:05 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:05 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:05 volumio volumio[1233]: info: May 27 21:12:05 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand stop took 27 milliseconds May 27 21:12:05 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand stop took 5 milliseconds May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear May 27 21:12:05 volumio volumio[1233]: info: May 27 21:12:05 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:12:05 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand status took 4 milliseconds May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand clear took 1 milliseconds May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" May 27 21:12:05 volumio volumio[1233]: error: updateQueue error: null May 27 21:12:05 volumio volumio[1233]: info: May 27 21:12:05 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:12:05 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:12:05 volumio volumio[1233]: info: ------------------------------ 5ms May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 3 milliseconds May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand add "https://stream.motherearthradio.de/listen/motherearth/motherearth" took 3 milliseconds May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:12:05 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play May 27 21:12:05 volumio volumio[1233]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') May 27 21:12:05 volumio volumio[1233]: info: ------------------------------ 13ms May 27 21:12:05 volumio volumio[1233]: info: ------------------------------ 7ms May 27 21:12:05 volumio volumio[1233]: info: sendMpdCommand play took 3 milliseconds May 27 21:12:05 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:05 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:05 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","title":"Connecting...","artist":"Mother Earth Radio","album":"Radio · FLAC 192kHz/24bit Stereo","albumart":"/albumart?sourceicon=music_service/motherearthradio/mer-logo-cube-bold-1x 512.png","uri":"motherearthradio/radio/flac192","streaming":true,"disableUiControls":true,"samplerate":"192 kHz","bitdepth":"24 bit","duration":0,"seek":0} May 27 21:12:05 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:12:05 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:12:06 volumio volumio[1233]: info: [MER] ✅ SSE connected May 27 21:12:06 volumio volumio[1233]: info: camilladsp respawn in 800 ms (attempt 4/10) May 27 21:12:06 volumio volumio[1233]: info: May 27 21:12:06 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:12:06 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:12:06 volumio volumio[1233]: info: May 27 21:12:06 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:12:06 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds May 27 21:12:06 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:12:06 volumio volumio[1233]: info: sendMpdCommand status took 0 milliseconds May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:12:06 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 21:12:06 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 1 milliseconds May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:12:06 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:12:06 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:12:06 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:06 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:06 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 27 21:12:06 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:12:06 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:06 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:06 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 27 21:12:06 volumio volumio[1233]: info: ------------------------------ 8ms May 27 21:12:06 volumio volumio[1233]: info: ------------------------------ 4ms May 27 21:12:06 volumio volumio[1233]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 May 27 21:12:06 volumio volumio[1233]: info: FusionDsp - ---- read samplerate from file: 192000 May 27 21:12:06 volumio volumio[1233]: info: camilladsp service terminated, instance 1 May 27 21:12:06 volumio volumio[1233]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 21:12:06 volumio volumio[1233]: info: camilladsp service started and running in background, instance 1 May 27 21:12:07 volumio volumio[1233]: info: May 27 21:12:07 volumio volumio[1233]: ---------------------------- MPD announces system playlist update May 27 21:12:07 volumio volumio[1233]: info: Ignoring MPD Status Update May 27 21:12:07 volumio volumio[1233]: info: May 27 21:12:07 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:12:07 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:12:07 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:12:07 volumio volumio[1233]: info: ------------------------------ 1ms May 27 21:12:07 volumio volumio[1233]: info: sendMpdCommand status took 2 milliseconds May 27 21:12:07 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:12:07 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:12:07 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 0 milliseconds May 27 21:12:07 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:12:07 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:12:07 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:07 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:07 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current motherearthradio Received mpd May 27 21:12:07 volumio volumio[1233]: info: ------------------------------ 3ms May 27 21:12:09 volumio volumio[1233]: info: [MER] 🎵 Andy McKee - Art Of Motion May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:09 volumio volumio[1233]: info: [MER] 📤 Andy McKee - Art Of Motion May 27 21:12:09 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:09 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth/art/c35df0f2674fa864b4382625-1772207849.jpg","uri":"motherearthradio/radio/flac192","name":"Art Of Motion","title":"Art Of Motion","artist":"Andy McKee","album":"Radio","streaming":true,"disableUiControls":true,"duration":208,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 27 21:12:09 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:12:09 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:12:09 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:12:09 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:12:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:09 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:12:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:09 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:09 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:09.566+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:12:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:09.566+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title="Art Of Motion" May 27 21:12:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:09.567+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:12:09 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:09.567+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title="Art Of Motion" May 27 21:12:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:12:09 volumio volumio[1233]: warn: FusionDsp - Monitor WebSocket not open, skipping commands May 27 21:12:09 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:12:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:12:09 volumio volumio[1233]: info: FusionDsp - Clipping Monitor started May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:09 volumio volumio[1233]: info: CorePlayQueue::getTrack 1 May 27 21:12:43 volumio volumio[1233]: info: [MER] 🎵 Manu Delago - Spaceful May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:43 volumio volumio[1233]: info: [MER] 📤 Manu Delago - Spaceful May 27 21:12:43 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:43 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","service":"motherearthradio","type":"webradio","trackType":"flac","radioType":"mer","albumart":"https://stream.motherearthradio.de/api/station/motherearth/art/db22428e4ff5c21449ae4592-1772207908.jpg","uri":"motherearthradio/radio/flac192","name":"Spaceful","title":"Spaceful","artist":"Manu Delago","album":"Metromonk","streaming":true,"disableUiControls":true,"duration":335,"seek":0,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2} May 27 21:12:43 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:12:43 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play May 27 21:12:43 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play May 27 21:12:43 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload May 27 21:12:43 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:12:43 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:43 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:43 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:43 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:12:43 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:43.686+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:12:43 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:43.686+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_PLAYING positionMs=0 volume=100 May 27 21:12:43 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:43.687+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:43 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:43.687+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:43 volumio volumio[1233]: info: FusionDsp - Volumio is playing May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:43 volumio volumio[1233]: info: CorePlayQueue::getTrack 1 May 27 21:12:47 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri May 27 21:12:48 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:51 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::ClearQueue May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::serviceStop May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::serviceStop May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","service":"motherearthradio"} May 27 21:12:51 volumio volumio[1233]: verbose: CURRENT POSITION 0 May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:51 volumio volumio[1233]: info: No code May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:51 volumio volumio[1233]: info: ControllerMpd::stop May 27 21:12:51 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::addQueueItems May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::addQueueItems May 27 21:12:51 volumio volumio[1233]: info: Preload queue cleared May 27 21:12:51 volumio volumio[1233]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D May 27 21:12:51 volumio volumio[1233]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D0%22%2C%22name%22%3A%22The%20Main%20Mix%22%2C%22title%22%3A%22The%20Main%20Mix%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F0%2Fcover_512x512%2F0.jpg%22%7D in service rp2 May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.638+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.638+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.640+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.640+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.640+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.640+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.640+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.641+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id=motherearthradio/radio/flac192 title=Spaceful May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::saveQueue May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::play index 0 May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::stop May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::play index undefined May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:51 volumio volumio[1233]: info: May 27 21:12:51 volumio volumio[1233]: ---------------------------- MPD announces state update: player May 27 21:12:51 volumio volumio[1233]: info: sendMpdCommand stop took 32 milliseconds May 27 21:12:51 volumio volumio[1233]: info: ControllerMpd::getState May 27 21:12:51 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status May 27 21:12:51 volumio volumio[1233]: info: sendMpdCommand status took 1 milliseconds May 27 21:12:51 volumio volumio[1233]: verbose: ControllerMpd::parseState May 27 21:12:51 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 27 21:12:51 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds May 27 21:12:51 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo May 27 21:12:51 volumio volumio[1233]: info: ControllerMpd::pushState May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::servicePushState May 27 21:12:51 volumio volumio[1233]: info: CoreStateMachine::pushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 21:12:51 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState May 27 21:12:51 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 May 27 21:12:51 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current rp2 Received mpd May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.677+04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" state=STATUS_STOPPED positionMs=0 volume=100 May 27 21:12:51 volumio volumio[1233]: info: ------------------------------ 9ms May 27 21:12:51 volumio volumio5-onboarding[1694]: time=2026-05-27T21:12:51.678+04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.110:49353 @ 0x250e840" id="rp2/channel@id=0" title="The Main Mix" May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Volumio is not playing May 27 21:12:51 volumio volumio[1233]: info: FusionDsp - Clipped samples monitor stopped May 27 21:12:51 volumio volumio[1233]: info: camilladsp respawn in 100 ms (attempt 1/10) May 27 21:12:52 volumio volumio[1233]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=0&audio_type= May 27 21:12:53 volumio volumio[1233]: info: [rp2] Obtained block for channel "0" May 27 21:12:53 volumio volumio[1233]: info: [rp2] ------------- May 27 21:12:53 volumio volumio[1233]: info: [rp2] Block summary May 27 21:12:53 volumio volumio[1233]: info: [rp2] ------------- May 27 21:12:53 volumio volumio[1233]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/0/x/1979/4/b/1979-0.flac May 27 21:12:53 volumio volumio[1233]: info: [rp2] Tracks: May 27 21:12:53 volumio volumio[1233]: info: [rp2] 0. Cherry Blossom Clinic Revisited (7:35 | elapsed: 23m) May 27 21:12:53 volumio volumio[1233]: info: [rp2] May 27 21:12:53 volumio volumio[1233]: verbose: [rp2] Current track scheduled playback vs. current time: 5/27/2026, 9:07:54 PM <-> 5/27/2026, 9:12:53 PM May 27 21:12:53 volumio volumio[1233]: info: [rp2] Going to start playback of current track at 4:58 (track position in stream: 23:00) May 27 21:12:53 volumio volumio[1233]: info: [rp2] Starting mpv May 27 21:12:53 volumio volumio[1233]: /bin/sh: 1: mpv: not found May 27 21:12:53 volumio volumio[1233]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version May 27 21:12:53 volumio volumio[1233]: /bin/sh: 1: mpv: not found May 27 21:12:53 volumio volumio[1233]: info: [rp2] [mpv] mpv process spawned May 27 21:12:53 volumio volumio[1233]: verbose: [rp2] Waiting for player event "playing"... May 27 21:12:53 volumio volumio[1233]: info: [rp2] [mpv] (PID: 3803) /bin/sh: 1: mpv: not found May 27 21:12:53 volumio volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 21:12:53 volumio volumio[1233]: TypeError: Cannot read properties of null (reading 'reset') May 27 21:12:53 volumio volumio[1233]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 May 27 21:12:53 volumio volumio[1233]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) May 27 21:12:53 volumio volumio[1233]: at ChildProcess.emit (node:events:514:28) May 27 21:12:53 volumio volumio[1233]: at maybeClose (node:internal/child_process:1105:16) May 27 21:12:53 volumio volumio[1233]: at Socket. (node:internal/child_process:457:11) May 27 21:12:53 volumio volumio[1233]: at Socket.emit (node:events:514:28) May 27 21:12:53 volumio volumio[1233]: at Pipe. (node:net:337:12) May 27 21:12:53 volumio volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 21:12:53 volumio sudo[3818]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 21:11' May 27 21:12:53 volumio sudo[3818]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"