Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces system playlist update Oct 03 10:14:25 rivo volumio[3229]: info: Ignoring MPD Status Update Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces state update: player Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::getState Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::sendMpdCommand status Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces system playlist update Oct 03 10:14:25 rivo volumio[3229]: info: Ignoring MPD Status Update Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces state update: player Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::getState Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::sendMpdCommand status Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces system playlist update Oct 03 10:14:25 rivo volumio[3229]: info: Ignoring MPD Status Update Oct 03 10:14:25 rivo volumio[3229]: info: Oct 03 10:14:25 rivo volumio[3229]: ---------------------------- MPD announces state update: player Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::getState Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::sendMpdCommand status Oct 03 10:14:25 rivo volumio[3229]: error: updateQueue error: null Oct 03 10:14:25 rivo volumio[3229]: error: updateQueue error: null Oct 03 10:14:25 rivo volumio[3229]: error: updateQueue error: null Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 11ms Oct 03 10:14:25 rivo volumio[3229]: info: sendMpdCommand status took 11 milliseconds Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 10ms Oct 03 10:14:25 rivo volumio[3229]: info: sendMpdCommand status took 9 milliseconds Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 9ms Oct 03 10:14:25 rivo volumio[3229]: info: sendMpdCommand status took 8 milliseconds Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::parseState Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::parseState Oct 03 10:14:25 rivo volumio[3229]: verbose: ControllerMpd::parseState Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: CorePlayQueue::getTrack 28 Oct 03 10:14:25 rivo volumio[3229]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 10:14:25 rivo volumio[3229]: verbose: CURRENT POSITION 28 Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState stateService stop Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState currentStatus play Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::stPlaybackTimer Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: CorePlayQueue::getTrack 0 Oct 03 10:14:25 rivo volumio[3229]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 10:14:25 rivo volumio[3229]: verbose: CURRENT POSITION 0 Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState stateService stop Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState currentStatus stop Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: No code Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: ControllerMpd::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: CorePlayQueue::getTrack 0 Oct 03 10:14:25 rivo volumio[3229]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 03 10:14:25 rivo volumio[3229]: verbose: CURRENT POSITION 0 Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState stateService stop Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::syncState currentStatus stop Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: No code Oct 03 10:14:25 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:25 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:25 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 82ms Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 82ms Oct 03 10:14:25 rivo volumio[3229]: info: ------------------------------ 83ms Oct 03 10:14:25 rivo volumio[3229]: info: MCU Signalled Playback Inactive Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:27 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:27 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:44 rivo volumio[3229]: info: CoreCommandRouter::volumioPlay Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::play index 0 Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::stop Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::play index undefined Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:44 rivo volumio[3229]: info: CorePlayQueue::getTrack 0 Oct 03 10:14:44 rivo volumio[3229]: info: CoreStateMachine::startPlaybackTimer Oct 03 10:14:44 rivo volumio[3229]: info: CorePlayQueue::getTrack 0 Oct 03 10:14:44 rivo volumio[3229]: info: [rp2] clearAddPlayTrack: rp2/channel@id=0 Oct 03 10:14:44 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Player status "stopped" - skip unset volatile because unset condition is "manual" Oct 03 10:14:44 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Player status "stopped" - skip unset volatile because unset condition is "manual" Oct 03 10:14:45 rivo volumio[3229]: 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= Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] Obtained block for channel "0" Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] ------------- Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] Block summary Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] ------------- Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] Stream URL: https://audio.radioparadise.stream/audio/blocks/0/x/2201/4/b/2201-0.flac Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] Tracks: Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 0. Umbra (3:08 | elapsed: 9m 21s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 1. Beautiful Way (5:25 | elapsed: 12m 30s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 2. Cuttin' Teeth (3:31 | elapsed: 17m 55s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 3. Pep Talk (3:05 | elapsed: 21m 27s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 4. Shadow Stabbing (3:00 | elapsed: 24m 33s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] 5. Children Of The Revolution (2:26 | elapsed: 27m 34s) Oct 03 10:14:45 rivo volumio[3229]: info: [rp2] Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Player status "stopped" - skip unset volatile because unset condition is "manual" Oct 03 10:14:46 rivo volumio[3229]: verbose: [rp2] Current track scheduled playback vs. current time: 10/3/2026, 10:13:39 AM <-> 10/3/2026, 10:14:46 AM Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] Going to start playback of current track at 1:06 (track position in stream: 9:21) Oct 03 10:14:46 rivo volumio[3229]: verbose: [rp2] Waiting for player event "playing"... Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:46 rivo volumio[3229]: info: CorePlayQueue::getTrack 0 Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Stopping playback by current service... Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioStop Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::stop Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Setting ourselves as the current service... Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Send IPC command: {"command":["set_property","loop-file","no"],"request_id":40} Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Got response for command #40: {"request_id":40,"error":"success"} Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Push Volumio state: {"status":"pause","albumart":"https://img.radioparadise.com/covers/l/22661.jpg","uri":"rp2/channel@id=0","seek":0,"duration":188.429,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"disableVolumeControl":false,"title":"Umbra","artist":"GoGo Penguin","album":"Necessary Fictions","codec":"flac","trackType":"The Main Mix"} Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Send IPC command: {"command":["loadfile","https://audio.radioparadise.stream/audio/blocks/0/x/2201/4/b/2201-0.flac","replace","start=628.084"],"request_id":41} Oct 03 10:14:46 rivo volumio5-onboarding[4913]: time=2026-10-03T10:14:46.020+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 188.429 into Go struct field State.duration of type int" Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Got response for command #41: {"data":{"playlist_entry_id":8},"request_id":41,"error":"success"} Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Status update -> property-change events: [{"subscriptionId":3,"prop":"idle-active","data":false},{"subscriptionId":8,"prop":"media-title","data":"2201-0.flac"}] Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Send IPC command: {"command":["set_property","pause",false],"request_id":42} Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Push Volumio state: {"status":"play","uri":"rp2/channel@id=0","title":"Umbra","artist":"GoGo Penguin","album":"Necessary Fictions","albumart":"https://img.radioparadise.com/covers/l/22661.jpg","trackType":"The Main Mix","duration":188.429,"service":"rp2","seek":0,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"disableVolumeControl":false,"codec":"flac"} Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:46 rivo volumio5-onboarding[4913]: time=2026-10-03T10:14:46.043+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 188.429 into Go struct field State.duration of type int" Oct 03 10:14:46 rivo volumio[3229]: info: Signalling Playback active due to playback status change Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Got response for command #42: {"request_id":42,"error":"success"} Oct 03 10:14:46 rivo volumio[3229]: info: MCU Signalled Playback Active Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) (+) Audio --aid=1 (flac 2ch 44100Hz) Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Status update -> property-change events: [{"subscriptionId":2,"prop":"duration","data":1800.441995}] Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Status update -> property-change events: [{"subscriptionId":7,"prop":"audio-codec-name","data":"flac"},{"subscriptionId":9,"prop":"metadata","data":{}}] Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Push Volumio state: {"status":"play","uri":"rp2/channel@id=0","title":"Umbra","artist":"GoGo Penguin","album":"Necessary Fictions","albumart":"https://img.radioparadise.com/covers/l/22661.jpg","trackType":"The Main Mix","duration":188.429,"service":"rp2","seek":66245,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"disableVolumeControl":false,"codec":"flac"} Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:46 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:46 rivo volumio[3229]: verbose: [rp2] Received player event "playing"... Oct 03 10:14:46 rivo volumio[3229]: info: [rp2] Started playback of "Umbra" - estimated finish time: 10/3/2026, 10:16:48 AM Oct 03 10:14:46 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:46 rivo volumio[3229]: verbose: [rp2] After "Umbra", the next track will start in 2m 2s (10/3/2026, 10:16:48 AM) Oct 03 10:14:46 rivo volumio[3229]: verbose: [rp2] API: https://api.radioparadise.com/api/update_history?source=24&song_id=53397&chan=0&player_id=********&event=2930322&type=M&slice_num=2&episode_id=0&time_relative=-67&play_position_millis=66245&playtime_secs=1791015287 Oct 03 10:14:46 rivo volumio5-onboarding[4913]: time=2026-10-03T10:14:46.461+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 188.429 into Go struct field State.duration of type int" Oct 03 10:14:48 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:48 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:48 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:48 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:48 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:48 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:48 rivo volumio[3229]: verbose: [rp2] Checking if next track interval requires adjusting (1/5)... Oct 03 10:14:48 rivo volumio[3229]: verbose: [rp2] Recalculated next track interval exceeds current by 2001ms. Going to re-adjust. Oct 03 10:14:50 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) AO: [alsa] 44100Hz stereo 2ch s16 Oct 03 10:14:50 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params","data":{"samplerate":44100,"channel-count":2,"channels":"stereo","hr-channels":"stereo","format":"s16"}}] Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:50 rivo volumio[3229]: info: [rp2] [mpv] (PID: 4997) Push Volumio state: {"status":"play","uri":"rp2/channel@id=0","title":"Umbra","artist":"GoGo Penguin","album":"Necessary Fictions","albumart":"https://img.radioparadise.com/covers/l/22661.jpg","trackType":"The Main Mix - ","duration":188.429,"samplerate":"44.1 kHz","bitdepth":"16-bit","channels":2,"service":"rp2","seek":66245,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"disableVolumeControl":false,"codec":"flac"} Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::servicePushState Oct 03 10:14:50 rivo volumio[3229]: info: CoreStateMachine::pushState Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::volumioPushState Oct 03 10:14:50 rivo volumio[3229]: info: MRS: Pushing multiroomSync output update for this device Oct 03 10:14:50 rivo volumio[3229]: info: MRS: Pushing multiroomSync output Oct 03 10:14:50 rivo volumio5-onboarding[4913]: time=2026-10-03T10:14:50.048+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 188.429 into Go struct field State.duration of type int" Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:50 rivo volumio[3229]: info: CoreCommandRouter::volumioGetState Oct 03 10:14:50 rivo volumio[3229]: verbose: [rp2] Checking if next track interval requires adjusting (2/5)... Oct 03 10:14:50 rivo volumio[3229]: verbose: [rp2] Recalculated next track interval exceeds current by 1648.628000000026ms. Going to re-adjust. Oct 03 10:14:52 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:52 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:52 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:52 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:52 rivo volumio[3229]: info: Executing endpoint metavolumio Oct 03 10:14:52 rivo volumio[3229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 03 10:14:52 rivo volumio[3229]: verbose: [rp2] Checking if next track interval requires adjusting (3/5)... Oct 03 10:14:52 rivo volumio[3229]: verbose: [rp2] Next track interval is accurate Oct 03 10:14:54 rivo volumio[3229]: verbose: [rp2] Checking if next track interval requires adjusting (4/5)... Oct 03 10:14:54 rivo volumio[3229]: verbose: [rp2] Next track interval is accurate Oct 03 10:14:56 rivo volumio[3229]: verbose: [rp2] Checking if next track interval requires adjusting (5/5)... Oct 03 10:14:56 rivo volumio[3229]: verbose: [rp2] Next track interval is accurate Oct 03 10:15:32 rivo volumio[3229]: info: [1791015332218] [80s80s] Pushing the next song state Cybordelics - Alice In Wonderland and getting next track. Oct 03 10:15:32 rivo volumio[3229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 10:15:32 rivo volumio[3229]: TypeError: Cannot set properties of undefined (setting 'name') Oct 03 10:15:32 rivo volumio[3229]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:565:20) Oct 03 10:15:32 rivo volumio[3229]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Oct 03 10:15:32 rivo volumio[3229]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Oct 03 10:15:32 rivo volumio[3229]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Oct 03 10:15:32 rivo volumio[3229]: at process.processImmediate (node:internal/timers:478:21) Oct 03 10:15:32 rivo volumio[3229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 10:15:33 rivo sudo[15784]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 10:14' Oct 03 10:15:33 rivo sudo[15784]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 18:35:21 UTC 2026" VOLUMIO_VERSION="4.209" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="d06564eaf7a6bebf8f928a4d13807037"