May 30 18:55:37 volumio volumio[1180]: info: Preload queue cleared May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::ClearQueue May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::serviceStop May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::serviceStop May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::stPlaybackTimer May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::updateTrackBlock May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::getTrackBlock May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["stop"],"request_id":35} May 30 18:55:37 volumio volumio[1180]: verbose: [rp2] Waiting for player event "stopped"... May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::clearPlayQueue May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::saveQueue May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::addQueueItems May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::addQueueItems May 30 18:55:37 volumio volumio[1180]: info: Preload queue cleared May 30 18:55:37 volumio volumio[1180]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D5%22%2C%22name%22%3A%22Beyond...%22%2C%22title%22%3A%22Beyond...%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F5%2Fcover_512x512%2F0.jpg%22%7D May 30 18:55:37 volumio volumio[1180]: info: Using cached record of: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D5%22%2C%22name%22%3A%22Beyond...%22%2C%22title%22%3A%22Beyond...%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F5%2Fcover_512x512%2F0.jpg%22%7D May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::saveQueue May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::updateTrackBlock May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::getTrackBlock May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPlay May 30 18:55:37 volumio volumio[1180]: verbose: UNSET VOLATILE: Service: rp2 May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Volatile state unset, stopping playback (if any)... May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"stop","albumart":"https://img.radioparadise.com/covers/l/19360.jpg","uri":"rp2/channel@id=3","seek":236971.98900000006,"duration":251.858,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"Pulse Antique","artist":"Oceanvs Orientalis","album":"Portrait of the Obscure","trackType":"flac","samplerate":"The Globe"} May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:37 volumio volumio[1180]: verbose: STATE SERVICE {"status":"stop","albumart":"https://img.radioparadise.com/covers/l/19360.jpg","uri":"rp2/channel@id=3","seek":236971.98900000006,"duration":251.858,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"Pulse Antique","artist":"Oceanvs Orientalis","album":"Portrait of the Obscure","trackType":"flac","samplerate":"The Globe"} May 30 18:55:37 volumio volumio[1180]: verbose: CURRENT POSITION 0 May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::syncState stateService stop May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus stop May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:37 volumio volumio[1180]: info: No code May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:37 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::play index 0 May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:37 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:37.577+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 236971.98900000006 into Go struct field State.seek of type int" May 30 18:55:37 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:37.577+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 236971.98900000006 into Go struct field State.seek of type int" May 30 18:55:37 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:37.579+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 236971.98900000006 into Go struct field State.seek of type int" May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::play index undefined May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:37 volumio volumio[1180]: info: CoreStateMachine::startPlaybackTimer May 30 18:55:37 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:37 volumio volumio[1180]: info: [rp2] clearAddPlayTrack: rp2/channel@id=5 May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #35: {"data":null,"request_id":35,"error":"success"} May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params"},{"subscriptionId":7,"prop":"audio-codec-name"},{"subscriptionId":2,"prop":"duration"},{"subscriptionId":3,"prop":"idle-active","data":true},{"subscriptionId":8,"prop":"media-title"},{"subscriptionId":9,"prop":"metadata"}] May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - unsetting ourselves as current service... May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - unsetting ourselves as current service... May 30 18:55:37 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) May 30 18:55:38 volumio volumio[1180]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=5&audio_type= May 30 18:55:38 volumio volumio[1180]: info: [rp2] Obtained block for channel "5" May 30 18:55:38 volumio volumio[1180]: info: [rp2] ------------- May 30 18:55:38 volumio volumio[1180]: info: [rp2] Block summary May 30 18:55:38 volumio volumio[1180]: info: [rp2] ------------- May 30 18:55:38 volumio volumio[1180]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/5/x/1451/4/b/1451-0.flac May 30 18:55:38 volumio volumio[1180]: info: [rp2] Tracks: May 30 18:55:38 volumio volumio[1180]: info: [rp2] 0. Summit Push (feat. Thodoris Ziarkas & Filippo Galli) (3:02 | elapsed: 25m 35s) May 30 18:55:38 volumio volumio[1180]: info: [rp2] May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - skip unset volatile because unset condition is "manual" May 30 18:55:38 volumio volumio[1180]: verbose: [rp2] Current track scheduled playback vs. current time: 5/30/2026, 6:50:54 PM <-> 5/30/2026, 6:55:38 PM May 30 18:55:38 volumio volumio[1180]: info: [rp2] Going to start playback of current track at 0:00 (track position in stream: 25:35) May 30 18:55:38 volumio volumio[1180]: verbose: [rp2] Waiting for player event "playing"... May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:38 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Stopping playback by current service... May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::volumioStop May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Setting ourselves as the current service... May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["set_property","loop-file","no"],"request_id":36} May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #36: {"request_id":36,"error":"success"} May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"pause","albumart":"https://img.radioparadise.com/covers/l/21874.jpg","uri":"rp2/channel@id=5","seek":0,"duration":182.482,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"Summit Push (feat. Thodoris Ziarkas & Filippo Galli)","artist":"Tom Sochas","album":"Facing North (feat. Thodoris Ziarkas & Filippo Galli)","trackType":"flac","samplerate":"Beyond..."} May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["loadfile","https://audio-geo.radioparadise.com/chan/5/x/1451/4/b/1451-0.flac","replace","start=1535.523"],"request_id":37} May 30 18:55:38 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:38.960+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 182.482 into Go struct field State.duration of type int" May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #37: {"data":{"playlist_entry_id":7},"request_id":37,"error":"success"} May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":3,"prop":"idle-active","data":false},{"subscriptionId":8,"prop":"media-title","data":"1451-0.flac"}] May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["set_property","pause",false],"request_id":38} May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"play","uri":"rp2/channel@id=5","title":"Summit Push (feat. Thodoris Ziarkas & Filippo Galli)","artist":"Tom Sochas","album":"Facing North (feat. Thodoris Ziarkas & Filippo Galli)","albumart":"https://img.radioparadise.com/covers/l/21874.jpg","trackType":"flac","duration":182.482,"samplerate":"Beyond...","service":"rp2","seek":0,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true} May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:55:38 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:55:38 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:38 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:38.969+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 182.482 into Go struct field State.duration of type int" May 30 18:55:38 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #38: {"request_id":38,"error":"success"} May 30 18:55:39 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) (+) Audio --aid=1 (flac 2ch 44100Hz) May 30 18:55:39 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":2,"prop":"duration","data":1718.005011},{"subscriptionId":7,"prop":"audio-codec-name","data":"flac"},{"subscriptionId":9,"prop":"metadata","data":{}}] May 30 18:55:39 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:39 volumio volumio[1180]: verbose: [rp2] Received player event "playing"... May 30 18:55:39 volumio volumio[1180]: info: [rp2] Started playback of "Summit Push (feat. Thodoris Ziarkas & Filippo Galli)" - estimated finish time: 5/30/2026, 6:58:42 PM May 30 18:55:39 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:39 volumio volumio[1180]: verbose: [rp2] After "Summit Push (feat. Thodoris Ziarkas & Filippo Galli)", prepare to fetch the next block in 3m 2s (5/30/2026, 6:58:42 PM) May 30 18:55:39 volumio volumio[1180]: verbose: [rp2] API: https://api.radioparadise.com/api/update_history?source=24&song_id=50459&chan=5&player_id=********&event=147055&type=M&slice_num=5&episode_id=0&time_relative=-0&play_position_millis=0&playtime_secs=1780160140 May 30 18:55:41 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) AO: [alsa] 44100Hz stereo 2ch s16 May 30 18:55:41 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params","data":{"samplerate":44100,"channel-count":2,"channels":"stereo","hr-channels":"stereo","format":"s16"}}] May 30 18:55:41 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:41 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"play","uri":"rp2/channel@id=5","title":"Summit Push (feat. Thodoris Ziarkas & Filippo Galli)","artist":"Tom Sochas","album":"Facing North (feat. Thodoris Ziarkas & Filippo Galli)","albumart":"https://img.radioparadise.com/covers/l/21874.jpg","trackType":"flac","duration":182.482,"samplerate":"44.1 kHz - Beyond...","bitdepth":"16-bit","channels":2,"service":"rp2","seek":0,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true} May 30 18:55:41 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:55:41 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:41 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:55:41 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:41 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:41.251+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 182.482 into Go struct field State.duration of type int" May 30 18:55:41 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:55:41 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (1/5)... May 30 18:55:41 volumio volumio[1180]: verbose: [rp2] Recalculated next track interval exceeds current by 1590.3079999999609ms. Going to re-adjust. May 30 18:55:43 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (2/5)... May 30 18:55:43 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:55:45 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (3/5)... May 30 18:55:45 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:55:47 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (4/5)... May 30 18:55:47 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:55:49 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (5/5)... May 30 18:55:49 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:55:59 volumio volumio[1180]: info: Preload queue cleared May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioReplaceandPlayItems May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::ClearQueue May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::serviceStop May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::serviceStop May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::stPlaybackTimer May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::updateTrackBlock May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::getTrackBlock May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["stop"],"request_id":39} May 30 18:55:59 volumio volumio[1180]: verbose: [rp2] Waiting for player event "stopped"... May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::clearPlayQueue May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::saveQueue May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::addQueueItems May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::addQueueItems May 30 18:55:59 volumio volumio[1180]: info: Preload queue cleared May 30 18:55:59 volumio volumio[1180]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D42%22%2C%22name%22%3A%22Serenity%22%2C%22title%22%3A%22Serenity%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F42%2Fcover_512x512%2F0.jpg%22%7D May 30 18:55:59 volumio volumio[1180]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D42%22%2C%22name%22%3A%22Serenity%22%2C%22title%22%3A%22Serenity%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F42%2Fcover_512x512%2F0.jpg%22%7D in service rp2 May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPushQueue May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::saveQueue May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::updateTrackBlock May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::getTrackBlock May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPlay May 30 18:55:59 volumio volumio[1180]: verbose: UNSET VOLATILE: Service: rp2 May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Volatile state unset, stopping playback (if any)... May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"stop","albumart":"https://img.radioparadise.com/covers/l/21874.jpg","uri":"rp2/channel@id=5","seek":17817.044999999925,"duration":182.482,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"Summit Push (feat. Thodoris Ziarkas & Filippo Galli)","artist":"Tom Sochas","album":"Facing North (feat. Thodoris Ziarkas & Filippo Galli)","trackType":"flac","samplerate":"Beyond..."} May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:59 volumio volumio[1180]: verbose: STATE SERVICE {"status":"stop","albumart":"https://img.radioparadise.com/covers/l/21874.jpg","uri":"rp2/channel@id=5","seek":17817.044999999925,"duration":182.482,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"Summit Push (feat. Thodoris Ziarkas & Filippo Galli)","artist":"Tom Sochas","album":"Facing North (feat. Thodoris Ziarkas & Filippo Galli)","trackType":"flac","samplerate":"Beyond..."} May 30 18:55:59 volumio volumio[1180]: verbose: CURRENT POSITION 0 May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::syncState stateService stop May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::syncState currentStatus stop May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:59 volumio volumio[1180]: info: No code May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:55:59 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::play index 0 May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:59 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:59.141+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 17817.044999999925 into Go struct field State.seek of type int" May 30 18:55:59 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:59.141+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 17817.044999999925 into Go struct field State.seek of type int" May 30 18:55:59 volumio volumio5-onboarding[1582]: time=2026-05-30T18:55:59.143+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 17817.044999999925 into Go struct field State.seek of type int" May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::play index undefined May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:59 volumio volumio[1180]: info: CoreStateMachine::startPlaybackTimer May 30 18:55:59 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:55:59 volumio volumio[1180]: info: [rp2] clearAddPlayTrack: rp2/channel@id=42 May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #39: {"data":null,"request_id":39,"error":"success"} May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params"},{"subscriptionId":7,"prop":"audio-codec-name"},{"subscriptionId":2,"prop":"duration"},{"subscriptionId":3,"prop":"idle-active","data":true},{"subscriptionId":8,"prop":"media-title"},{"subscriptionId":9,"prop":"metadata"}] May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - unsetting ourselves as current service... May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - unsetting ourselves as current service... May 30 18:55:59 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) May 30 18:55:59 volumio volumio[1180]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=42&audio_type= May 30 18:56:00 volumio volumio[1180]: info: [rp2] Obtained block for channel "42" May 30 18:56:00 volumio volumio[1180]: info: [rp2] ------------- May 30 18:56:00 volumio volumio[1180]: info: [rp2] Block summary May 30 18:56:00 volumio volumio[1180]: info: [rp2] ------------- May 30 18:56:00 volumio volumio[1180]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/42/x/4270/4/b/4270-0.flac May 30 18:56:00 volumio volumio[1180]: info: [rp2] Tracks: May 30 18:56:00 volumio volumio[1180]: info: [rp2] 0. 4U 1543-475 (14:42 | elapsed: 14m 37s) May 30 18:56:00 volumio volumio[1180]: info: [rp2] May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Player status "stopped" - skip unset volatile because unset condition is "manual" May 30 18:56:00 volumio volumio[1180]: verbose: [rp2] Current track scheduled playback vs. current time: 5/30/2026, 6:55:35 PM <-> 5/30/2026, 6:56:00 PM May 30 18:56:00 volumio volumio[1180]: info: [rp2] Going to start playback of current track at 0:25 (track position in stream: 14:37) May 30 18:56:00 volumio volumio[1180]: verbose: [rp2] Waiting for player event "playing"... May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:00 volumio volumio[1180]: info: CorePlayQueue::getTrack 0 May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Stopping playback by current service... May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioStop May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::stop May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Setting ourselves as the current service... May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::setConsumeUpdateService undefined May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["set_property","loop-file","no"],"request_id":40} May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #40: {"request_id":40,"error":"success"} May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"pause","albumart":"https://img.radioparadise.com/covers/l/19334.jpg","uri":"rp2/channel@id=42","seek":0,"duration":882.726,"service":"rp2","stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true,"title":"4U 1543-475","artist":"Rule 42","album":"Ambient Explorations 04","trackType":"flac","samplerate":"Serenity"} May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["loadfile","https://audio-geo.radioparadise.com/chan/42/x/4270/4/b/4270-0.flac","replace","start=902.54"],"request_id":41} May 30 18:56:00 volumio volumio5-onboarding[1582]: time=2026-05-30T18:56:00.513+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 882.726 into Go struct field State.duration of type int" May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #41: {"data":{"playlist_entry_id":8},"request_id":41,"error":"success"} May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":3,"prop":"idle-active","data":false},{"subscriptionId":8,"prop":"media-title","data":"4270-0.flac"}] May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Send IPC command: {"command":["set_property","pause",false],"request_id":42} May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"play","uri":"rp2/channel@id=42","title":"4U 1543-475","artist":"Rule 42","album":"Ambient Explorations 04","albumart":"https://img.radioparadise.com/covers/l/19334.jpg","trackType":"flac","duration":882.726,"samplerate":"Serenity","service":"rp2","seek":0,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true} May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:56:00 volumio volumio5-onboarding[1582]: time=2026-05-30T18:56:00.529+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 882.726 into Go struct field State.duration of type int" May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Got response for command #42: {"request_id":42,"error":"success"} May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) (+) Audio --aid=1 (flac 2ch 44100Hz) May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":2,"prop":"duration","data":1759.840998}] May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":7,"prop":"audio-codec-name","data":"flac"},{"subscriptionId":9,"prop":"metadata","data":{}}] May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:00 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"play","uri":"rp2/channel@id=42","title":"4U 1543-475","artist":"Rule 42","album":"Ambient Explorations 04","albumart":"https://img.radioparadise.com/covers/l/19334.jpg","trackType":"flac","duration":882.726,"samplerate":"Serenity","service":"rp2","seek":25426,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true} May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:56:00 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:56:00 volumio volumio[1180]: verbose: [rp2] Received player event "playing"... May 30 18:56:00 volumio volumio[1180]: info: [rp2] Started playback of "4U 1543-475" - estimated finish time: 5/30/2026, 7:10:18 PM May 30 18:56:00 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:00 volumio volumio[1180]: verbose: [rp2] After "4U 1543-475", prepare to fetch the next block in 14m 17s (5/30/2026, 7:10:18 PM) May 30 18:56:00 volumio volumio[1180]: verbose: [rp2] API: https://api.radioparadise.com/api/update_history?source=24&song_id=46160&chan=42&player_id=********&event=31360&type=M&slice_num=1&episode_id=0&time_relative=-26&play_position_millis=25426&playtime_secs=1780160161 May 30 18:56:00 volumio volumio5-onboarding[1582]: time=2026-05-30T18:56:00.955+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 882.726 into Go struct field State.duration of type int" May 30 18:56:02 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (1/5)... May 30 18:56:02 volumio volumio[1180]: verbose: [rp2] Recalculated next track interval exceeds current by 2003ms. Going to re-adjust. May 30 18:56:04 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) AO: [alsa] 44100Hz stereo 2ch s16 May 30 18:56:04 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Status update -> property-change events: [{"subscriptionId":6,"prop":"audio-params","data":{"samplerate":44100,"channel-count":2,"channels":"stereo","hr-channels":"stereo","format":"s16"}}] May 30 18:56:04 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:04 volumio volumio[1180]: info: [rp2] [mpv] (PID: 4077) Push Volumio state: {"status":"play","uri":"rp2/channel@id=42","title":"4U 1543-475","artist":"Rule 42","album":"Ambient Explorations 04","albumart":"https://img.radioparadise.com/covers/l/19334.jpg","trackType":"flac","duration":882.726,"samplerate":"44.1 kHz - Serenity","bitdepth":"16-bit","channels":2,"service":"rp2","seek":25426,"stream":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":true} May 30 18:56:04 volumio volumio[1180]: info: CoreCommandRouter::servicePushState May 30 18:56:04 volumio volumio[1180]: info: CoreStateMachine::pushState May 30 18:56:04 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 30 18:56:04 volumio volumio[1180]: info: CoreCommandRouter::volumioPushState May 30 18:56:04 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! May 30 18:56:04 volumio volumio5-onboarding[1582]: time=2026-05-30T18:56:04.864+02:00 level=ERROR msg="failed to unmarshal pushState event" component=volumio/socket error="json: cannot unmarshal number 882.726 into Go struct field State.duration of type int" May 30 18:56:04 volumio volumio[1180]: info: CoreCommandRouter::volumioGetState May 30 18:56:04 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (2/5)... May 30 18:56:04 volumio volumio[1180]: verbose: [rp2] Recalculated next track interval exceeds current by 1909.235999999917ms. Going to re-adjust. May 30 18:56:06 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (3/5)... May 30 18:56:06 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:56:08 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (4/5)... May 30 18:56:08 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:56:10 volumio volumio[1180]: verbose: [rp2] Checking if next track interval requires adjusting (5/5)... May 30 18:56:10 volumio volumio[1180]: verbose: [rp2] Next track interval is accurate May 30 18:56:56 volumio volumio[1180]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri May 30 18:56:56 volumio volumio[1180]: info: Preload queue cleared May 30 18:56:56 volumio volumio[1180]: info: Preloading song: https://stream.radioparadise.com/mellow-flacm May 30 18:56:56 volumio volumio[1180]: info: Preloading song: http://stream.radioparadise.com/beyond-flacm May 30 18:56:56 volumio volumio[1180]: info: Preloading song: https://stream.radioparadise.com/flacm May 30 18:56:56 volumio volumio[1180]: info: Preloading song: motherearthradio/instrumental/flac192 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: motherearthradio/jazz/flac192 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: http://stream.radioparadise.com/global-flacm May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$1A$0$4 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$1A$0$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$1A$0$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$1A$0$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$1A$0$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$21 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$20 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1F May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1E May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$1D May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$D May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$51$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$23 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$22 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$21 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$D May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$4$11 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$4$10 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$4$F May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$4$E May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$4$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$B May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$A May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$5 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$4 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$D$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$5 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$4 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$A$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$6$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$5 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$4 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$5$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$1$9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$34$0$3$1$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$73$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$73$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$4F$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: motherearthradio/radio/flac96 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11E$4 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11E$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11E$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11E$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11E$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$C May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$A May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$5 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$1 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$64$11D$0 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$5 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$3 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: upnp/http://192.168.178.25:8200/ctl/ContentDir@1$14$9$2 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@13 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@12 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@11 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@10 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@9 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@8 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@7 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@6 May 30 18:56:56 volumio volumio[1180]: info: Preloading song: cue://USB/AA70-BD58/Music/Test CD/B&W with Real World Record (By SuperStalker)/Unknown Artist - B&W with Real World Record.cue@5 May 30 18:56:56 volumio volumio[1180]: info: Exploding uri https://stream.radioparadise.com/mellow-flacm in service radio_paradise May 30 18:56:56 volumio volumio[1180]: error: [RadioParadise] explodeUri: No station found for uri "https://stream.radioparadise.com/mellow-flacm" May 30 18:56:56 volumio volumio[1180]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:56:56 volumio volumio[1180]: TypeError: Cannot read properties of undefined (reading 'then') May 30 18:56:56 volumio volumio[1180]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:31) May 30 18:56:56 volumio volumio[1180]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) May 30 18:56:56 volumio volumio[1180]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) May 30 18:56:56 volumio volumio[1180]: at Timeout._onTimeout (/volumio/app/playqueue.js:146:100) May 30 18:56:56 volumio volumio[1180]: at listOnTimeout (node:internal/timers:573:17) May 30 18:56:56 volumio volumio[1180]: at process.processTimers (node:internal/timers:514:7) May 30 18:56:56 volumio volumio[1180]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 30 18:56:57 volumio sudo[5241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-30 18:55' May 30 18:56:57 volumio sudo[5241]: 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"