Aug 30 16:55:06 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f401dd20] http demux error: local stream 9 error: Cancellation (0x8) Aug 30 16:55:10 primo volumio[10717]: info: Preload queue cleared Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::ClearQueue Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::clearPlayQueue Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::saveQueue Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::addQueueItems Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::addQueueItems Aug 30 16:55:10 primo volumio[10717]: info: Preload queue cleared Aug 30 16:55:10 primo volumio[10717]: info: Adding Item to queue: soundcloud/playlists@playlistId=1780168443 Aug 30 16:55:10 primo volumio[10717]: info: Using cached record of: soundcloud/playlists@playlistId=1780168443 Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::saveQueue Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:10 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":12000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":12000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:10 primo volumio[10717]: verbose: CURRENT POSITION 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::play index 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.242+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.242+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.242+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.243+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.244+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.244+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.245+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.245+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:55:10 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:55:10 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.265+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.265+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.266+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.266+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:55:10 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:55:10 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:10 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:10 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:10 primo volumio[10717]: info: sendMpdCommand stop took 55 milliseconds Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/e7fc684f-6786-4c06-a007-bbb7bf410273/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/1dc88687-2f82-4308-b98c-168158f6198c/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/83b4636a-b87c-44bc-be75-3324a2ec7a5f/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/ee76b1c4-746a-4acb-b3e2-4ee62d3bf562/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/ee76b1c4-746a-4acb-b3e2-4ee62d3bf562/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/e7fc684f-6786-4c06-a007-bbb7bf410273/stream/hls"} Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-4GgrRAmIxWKXsslL-wbZStQ-t500x500.jpg","uri":"soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":12000,"duration":0,"service":"soundcloud","artist":"TIANA BROOKS","album":"SoundCloud Track","name":"ONE WISH","title":"ONE WISH","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:55:10 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:10 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.499+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.499+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=12000 volume=95 Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.500+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:10.500+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:10 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:55:10 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:55:10 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f401dd20] http demux error: local stream 11 error: Cancellation (0x8) Aug 30 16:55:10 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f401dd20] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:55:11 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f32ea9c0] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:55:11 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f34dd670] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:55:11 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:55:11 primo kernel: spdif_a is set to disable Aug 30 16:55:11 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:55:11 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:55:11 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:55:11 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:55:11 primo volumio[10717]: info: camilladsp respawn in 800 ms (attempt 4/10) Aug 30 16:55:11 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:55:11 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:55:11 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:55:11 primo volumio[10717]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 30 16:55:12 primo kernel: aml_tdm_open Aug 30 16:55:12 primo kernel: Not init audio effects Aug 30 16:55:12 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:55:12 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:55:12 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:55:12 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:55:12 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:55:12 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:55:12 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:55:12 primo kernel: pcm_mode(1) Aug 30 16:55:12 primo kernel: sysclk(11289600) Aug 30 16:55:12 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:55:12 primo kernel: bclk(2822400) Aug 30 16:55:12 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:55:12 primo kernel: lrclk(44100) Aug 30 16:55:12 primo kernel: tx_mask(0x3) Aug 30 16:55:12 primo kernel: rx_mask(0x3) Aug 30 16:55:12 primo kernel: slots(2) Aug 30 16:55:12 primo kernel: slot_width(32) Aug 30 16:55:12 primo kernel: lane_mask_in(0x2) Aug 30 16:55:12 primo kernel: lane_mask_out(0x1) Aug 30 16:55:12 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:55:12 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:55:12 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:55:12 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:55:12 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:55:12 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:55:12 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:55:12 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:55:12 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:55:12 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:55:12 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:55:12 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:55:12 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:55:12 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:55:12 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:55:12 primo kernel: spdif_a is set to enable Aug 30 16:55:13 primo volumio[10717]: info: Preload queue cleared Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::ClearQueue Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::clearPlayQueue Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::saveQueue Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::addQueueItems Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::addQueueItems Aug 30 16:55:13 primo volumio[10717]: info: Preload queue cleared Aug 30 16:55:13 primo volumio[10717]: info: Adding Item to queue: soundcloud/playlists@playlistId=1780168443 Aug 30 16:55:13 primo volumio[10717]: info: Using cached record of: soundcloud/playlists@playlistId=1780168443 Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushQueue Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::saveQueue Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:13 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":1000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":1000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:13 primo volumio[10717]: verbose: CURRENT POSITION 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::play index 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.136+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.136+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.137+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.137+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.138+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.139+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.140+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:55:13 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:55:13 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.154+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.154+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.155+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.155+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:55:13 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:55:13 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:13 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:13 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:13 primo volumio[10717]: info: sendMpdCommand stop took 43 milliseconds Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/e7fc684f-6786-4c06-a007-bbb7bf410273/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/1dc88687-2f82-4308-b98c-168158f6198c/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/83b4636a-b87c-44bc-be75-3324a2ec7a5f/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/ee76b1c4-746a-4acb-b3e2-4ee62d3bf562/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/ee76b1c4-746a-4acb-b3e2-4ee62d3bf562/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367664820/e7fc684f-6786-4c06-a007-bbb7bf410273/stream/hls"} Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-4GgrRAmIxWKXsslL-wbZStQ-t500x500.jpg","uri":"soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":1000,"duration":0,"service":"soundcloud","artist":"TIANA BROOKS","album":"SoundCloud Track","name":"ONE WISH","title":"ONE WISH","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:55:13 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.290+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.290+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=1000 volume=95 Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.291+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:13.291+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:13 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:55:13 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:55:13 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f32ea9c0] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:55:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:55:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f4000530] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:55:14 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:55:14 primo kernel: spdif_a is set to disable Aug 30 16:55:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:55:14 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:55:14 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:55:14 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:55:14 primo volumio[10717]: info: camilladsp respawn in 1600 ms (attempt 5/10) Aug 30 16:55:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 3 error: Cancellation (0x8) Aug 30 16:55:14 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:55:14 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:55:14 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:55:14 primo volumio[10717]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 30 16:55:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 5 error: Cancellation (0x8) Aug 30 16:55:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 7 error: Cancellation (0x8) Aug 30 16:55:15 primo kernel: aml_tdm_open Aug 30 16:55:15 primo kernel: Not init audio effects Aug 30 16:55:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:55:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:55:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:55:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:55:15 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:55:15 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:55:15 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:55:15 primo kernel: pcm_mode(1) Aug 30 16:55:15 primo kernel: sysclk(11289600) Aug 30 16:55:15 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:55:15 primo kernel: bclk(2822400) Aug 30 16:55:15 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:55:15 primo kernel: lrclk(44100) Aug 30 16:55:15 primo kernel: tx_mask(0x3) Aug 30 16:55:15 primo kernel: rx_mask(0x3) Aug 30 16:55:15 primo kernel: slots(2) Aug 30 16:55:15 primo kernel: slot_width(32) Aug 30 16:55:15 primo kernel: lane_mask_in(0x2) Aug 30 16:55:15 primo kernel: lane_mask_out(0x1) Aug 30 16:55:15 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:55:15 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:55:15 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:55:15 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:55:15 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:55:15 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:55:15 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:55:15 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:55:15 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:55:15 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:55:15 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:55:15 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:55:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:55:15 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:55:15 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:55:15 primo kernel: spdif_a is set to enable Aug 30 16:55:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 9 error: Cancellation (0x8) Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioNext Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::next Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:27 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 11 error: Cancellation (0x8) Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f331e670] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Player status "stopped" - unsetting ourselves as current service... Aug 30 16:55:27 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:27 primo volumio[10717]: info: CorePlayQueue::getTrack 0 Aug 30 16:55:27 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:55:27 primo volumio[10717]: verbose: CURRENT POSITION 0 Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus stop Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:27 primo volumio[10717]: info: No code Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:55:27 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:55:27 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.834+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.834+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.835+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.835+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.837+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.837+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.837+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.839+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.839+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.840+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.227:49879 @ 0x1f44de0" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:27.840+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367664820@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="ONE WISH" Aug 30 16:55:27 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:27 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:27 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:27 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:55:27 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:55:27 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:27 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:27 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:55:28 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:55:28 primo kernel: spdif_a is set to disable Aug 30 16:55:28 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:55:28 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:55:28 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:55:28 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:55:28 primo volumio[10717]: info: camilladsp respawn in 3200 ms (attempt 6/10) Aug 30 16:55:33 primo volumio[10717]: info: CoreCommandRouter::volumioVolatilePlay Aug 30 16:55:33 primo volumio[10717]: info: CoreStateMachine::volatilePlay Aug 30 16:55:33 primo volumio[10717]: info: WARNING: Received volatile play command but volumio is not on volatile state Aug 30 16:55:35 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:35.932+02:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.1.227:49879 error="read tcp 192.168.1.128:7331->192.168.1.227:49879: read: no route to host" Aug 30 16:55:35 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:35.933+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.227:49879 Aug 30 16:55:35 primo volumio5-onboarding[3951]: time=2026-08-30T16:55:35.933+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.227:49879 Aug 30 16:55:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:42 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:42 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:42 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:42 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:42 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:42 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:42 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:42 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:42 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:44 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:44 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:44 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:44 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:44 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:44 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:44 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:44 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:44 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:46 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:46 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:46 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:46 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:46 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:46 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:46 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:46 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:46 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:46 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:47 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:47 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:47 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:47 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:47 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:47 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:47 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:47 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:47 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:47 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:52 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:52 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:52 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:52 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:52 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:52 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:52 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:52 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:52 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:52 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:54 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:55:54 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:54 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:55:54 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:55:54 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:54 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:55:54 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:55:54 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:55:54 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:55:54 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:55:57 primo volumio[10717]: info: CoreCommandRouter::volumioVolatilePlay Aug 30 16:55:57 primo volumio[10717]: info: CoreStateMachine::volatilePlay Aug 30 16:55:57 primo volumio[10717]: info: WARNING: Received volatile play command but volumio is not on volatile state Aug 30 16:56:10 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:10 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:10 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:10 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:10 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:10 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:10 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:10 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:10 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:10 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:56:11 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:11 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:11 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:11 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::play index 1 Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:11 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:11 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:11 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:11 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2332893134@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:11 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/cbc-encrypted-hls","preset":"aac_160k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/36b27900-c6cc-4adc-9c8e-8736d1f19056/stream/ctr-encrypted-hls","preset":"aac_160k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/cbc-encrypted-hls","preset":"aac_96k","protocol":"cbc-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/31ffd905-f281-49e0-b4f5-d187c42cccde/stream/ctr-encrypted-hls","preset":"aac_96k","protocol":"ctr-encrypted-hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/cbc-encrypted-hls","preset":"abr_sq","protocol":"cbc-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/23cfe3e7-5e87-4954-8c20-8bb3d3e85255/stream/ctr-encrypted-hls","preset":"abr_sq","protocol":"ctr-encrypted-hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:11 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"mp3_1_0+http","codec":"mp3","protocol":"http","bitrate":"128 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2332893134/5ce24207-38f1-48ab-af12-bcb2add80302/stream/progressive"} Aug 30 16:56:13 primo bluealsa[3353]: bluez.c:1393: Signal: org.freedesktop.DBus.ObjectManager.InterfacesRemoved(/org/bluez/hci0/dev_68_3C_E5_2E_D8_A4, ...) Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:13 primo volumio[10717]: info: CorePlayQueue::getTrack 1 Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::play index 2 Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:13 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/8eb616c2-1769-4164-946b-7eec527b46f4/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/c608a47a-3b5b-4161-9955-b85bbc2d52b3/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/e0bb465b-2924-43dd-b4bb-ee4c61cb7f31/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/be623a64-ec0b-4b1b-aef7-4078b0084f64/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/be623a64-ec0b-4b1b-aef7-4078b0084f64/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/8eb616c2-1769-4164-946b-7eec527b46f4/stream/hls"} Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:13 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:13 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:13 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-R5W0ymLmjoiJRA5b-FBxE0Q-t500x500.jpg","uri":"soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":0,"duration":0,"service":"soundcloud","artist":"$tunna J 🤙🏽","album":"SoundCloud Track","name":"Don't Worry","title":"Don't Worry","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:13 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:13 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:13 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:13.974+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:13 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:13.975+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:13 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","title":"Don't Worry","artist":"$tunna J 🤙🏽","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-R5W0ymLmjoiJRA5b-FBxE0Q-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:14 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:14 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:14 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:14 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:14.038+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PLAYING positionMs=0 volume=95 Aug 30 16:56:14 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:14.038+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:14 primo volumio[10717]: info: FusionDsp - Volumio is playing Aug 30 16:56:14 primo volumio[10717]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Aug 30 16:56:14 primo volumio[10717]: info: Signalling Playback active due to playback status change Aug 30 16:56:14 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:14 primo volumio[10717]: error: FusionDsp - Monitor WebSocket error: [object Object] Aug 30 16:56:14 primo volumio[10717]: info: FusionDsp - Clipping Monitor reconnecting in 8000ms Aug 30 16:56:14 primo volumio[10717]: info: MCU Signalled Playback Active Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","title":"Don't Worry","artist":"$tunna J 🤙🏽","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-R5W0ymLmjoiJRA5b-FBxE0Q-t500x500.jpg","trackType":"aac","duration":237,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:14 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:14 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:14 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:14 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:14 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:14.383+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PLAYING positionMs=0 volume=95 Aug 30 16:56:14 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:14.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:14 primo volumio[10717]: info: Signalling Playback active due to playback status change Aug 30 16:56:14 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f1ff8f68] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:14 primo bluealsa[3353]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_68_3C_E5_2E_D8_A4, ...) Aug 30 16:56:14 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f4207e00] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:14 primo kernel: aml_tdm_open Aug 30 16:56:14 primo kernel: Not init audio effects Aug 30 16:56:14 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:56:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:14 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:56:14 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:56:14 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:56:14 primo kernel: pcm_mode(1) Aug 30 16:56:14 primo kernel: sysclk(11289600) Aug 30 16:56:14 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:56:14 primo kernel: bclk(2822400) Aug 30 16:56:14 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:56:14 primo kernel: lrclk(44100) Aug 30 16:56:14 primo kernel: tx_mask(0x3) Aug 30 16:56:14 primo kernel: rx_mask(0x3) Aug 30 16:56:14 primo kernel: slots(2) Aug 30 16:56:14 primo kernel: slot_width(32) Aug 30 16:56:14 primo kernel: lane_mask_in(0x2) Aug 30 16:56:14 primo kernel: lane_mask_out(0x1) Aug 30 16:56:14 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:56:14 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:56:14 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:56:14 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:14 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:14 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:14 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:56:14 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:56:14 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:56:14 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:56:14 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:56:14 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:56:14 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:14 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f1ff8f68] http demux error: local stream 3 error: Cancellation (0x8) Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:56:15 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:56:15 primo kernel: spdif_a is set to enable Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:15 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:15 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:15 primo volumio[10717]: verbose: CURRENT POSITION 2 Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus play Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::play index 2 Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.218+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PLAYING positionMs=0 volume=95 Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.219+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.220+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PLAYING positionMs=0 volume=95 Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.220+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.240+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.241+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/10bb32b7-46ef-4ba3-8a10-307e7db99bf7/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ce3b2f83-7feb-4883-9e1f-ed203b1135ad/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls"} Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/8eb616c2-1769-4164-946b-7eec527b46f4/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/c608a47a-3b5b-4161-9955-b85bbc2d52b3/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/e0bb465b-2924-43dd-b4bb-ee4c61cb7f31/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/be623a64-ec0b-4b1b-aef7-4078b0084f64/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/be623a64-ec0b-4b1b-aef7-4078b0084f64/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2380209144/8eb616c2-1769-4164-946b-7eec527b46f4/stream/hls"} Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:15 primo volumio[10717]: info: Signalling Playback active due to playback status change Aug 30 16:56:15 primo volumio[10717]: info: Signalling Playback active due to playback status change Aug 30 16:56:15 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:15 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:15 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f1ff8f68] http demux error: local stream 5 error: Cancellation (0x8) Aug 30 16:56:15 primo volumio[10717]: [f1ff8f68] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-QkyrBosDDjM31y1V-qo2mtw-t500x500.jpg","uri":"soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":0,"duration":0,"service":"soundcloud","artist":"osho.mo","album":"SoundCloud Track","name":"Thank You God","title":"Thank You God","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.346+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.346+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:15 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:15 primo volumio[10717]: info: MCU Signalled Playback Inactive Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-R5W0ymLmjoiJRA5b-FBxE0Q-t500x500.jpg","uri":"soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":0,"duration":0,"service":"soundcloud","artist":"$tunna J 🤙🏽","album":"SoundCloud Track","name":"Don't Worry","title":"Don't Worry","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:15 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:15 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:15 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:15 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:15.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:15 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f19fbe08] main tls client error: cannot resolve playback.media-streaming.soundcloud.cloud port 443: System error Aug 30 16:56:15 primo volumio[10717]: [f4009840] access stream error: HTTP connection failure Aug 30 16:56:15 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:56:15 primo kernel: spdif_a is set to disable Aug 30 16:56:15 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:15 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:56:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:56:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:56:15 primo volumio[10717]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 30 16:56:15 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f4050c60] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:16 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f45929b8] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:16 primo kernel: aml_tdm_open Aug 30 16:56:16 primo kernel: Not init audio effects Aug 30 16:56:16 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:56:16 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:16 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:16 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:16 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:56:16 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:56:16 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:56:16 primo kernel: pcm_mode(1) Aug 30 16:56:16 primo kernel: sysclk(11289600) Aug 30 16:56:16 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:56:16 primo kernel: bclk(2822400) Aug 30 16:56:16 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:56:16 primo kernel: lrclk(44100) Aug 30 16:56:16 primo kernel: tx_mask(0x3) Aug 30 16:56:16 primo kernel: rx_mask(0x3) Aug 30 16:56:16 primo kernel: slots(2) Aug 30 16:56:16 primo kernel: slot_width(32) Aug 30 16:56:16 primo kernel: lane_mask_in(0x2) Aug 30 16:56:16 primo kernel: lane_mask_out(0x1) Aug 30 16:56:16 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:56:16 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:56:16 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:56:16 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:16 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:16 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:16 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:56:16 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:56:16 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:56:16 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:56:16 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:56:16 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:56:16 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:16 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:56:16 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:56:16 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:56:16 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:56:16 primo kernel: spdif_a is set to enable Aug 30 16:56:16 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:22 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":5000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":5000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:22 primo volumio[10717]: verbose: CURRENT POSITION 2 Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::play index 3 Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.724+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=5000 volume=95 Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.725+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.725+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=5000 volume=95 Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.725+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 2 Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:56:22 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:56:22 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.743+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.744+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2380209144@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Don't Worry" Aug 30 16:56:22 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:22 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:22 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:22 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:22 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:22 primo volumio[10717]: info: sendMpdCommand stop took 42 milliseconds Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/10bb32b7-46ef-4ba3-8a10-307e7db99bf7/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ce3b2f83-7feb-4883-9e1f-ed203b1135ad/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls"} Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:22 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-QkyrBosDDjM31y1V-qo2mtw-t500x500.jpg","uri":"soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":5000,"duration":0,"service":"soundcloud","artist":"osho.mo","album":"SoundCloud Track","name":"Thank You God","title":"Thank You God","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:22 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:22 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:22 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.961+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=5000 volume=95 Aug 30 16:56:22 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:22.961+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:22 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:22 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:22 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f4050c60] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [acb5e7c8] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:23 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:56:23 primo kernel: spdif_a is set to disable Aug 30 16:56:23 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:23 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:56:23 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:56:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:56:23 primo volumio[10717]: info: camilladsp respawn in 200 ms (attempt 2/10) Aug 30 16:56:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:23 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 3 error: Cancellation (0x8) Aug 30 16:56:24 primo kernel: aml_tdm_open Aug 30 16:56:24 primo kernel: Not init audio effects Aug 30 16:56:24 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:56:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:24 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:56:24 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:56:24 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:56:24 primo kernel: pcm_mode(1) Aug 30 16:56:24 primo kernel: sysclk(11289600) Aug 30 16:56:24 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:56:24 primo kernel: bclk(2822400) Aug 30 16:56:24 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:56:24 primo kernel: lrclk(44100) Aug 30 16:56:24 primo kernel: tx_mask(0x3) Aug 30 16:56:24 primo kernel: rx_mask(0x3) Aug 30 16:56:24 primo kernel: slots(2) Aug 30 16:56:24 primo kernel: slot_width(32) Aug 30 16:56:24 primo kernel: lane_mask_in(0x2) Aug 30 16:56:24 primo kernel: lane_mask_out(0x1) Aug 30 16:56:24 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:56:24 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:56:24 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:56:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:24 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:56:24 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:56:24 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:56:24 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:56:24 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:56:24 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:56:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:24 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:56:24 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:56:24 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:56:24 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:56:24 primo kernel: spdif_a is set to enable Aug 30 16:56:24 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:56:24 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 5 error: Cancellation (0x8) Aug 30 16:56:24 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 7 error: Cancellation (0x8) Aug 30 16:56:32 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 9 error: Cancellation (0x8) Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:40 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":15000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":15000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:40 primo volumio[10717]: verbose: CURRENT POSITION 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::play index 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.311+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=15000 volume=95 Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.312+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.312+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=15000 volume=95 Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.313+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:56:40 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:56:40 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.330+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.331+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:40 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:40 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:40 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:40 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:40 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:40 primo volumio[10717]: info: sendMpdCommand stop took 45 milliseconds Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/10bb32b7-46ef-4ba3-8a10-307e7db99bf7/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ce3b2f83-7feb-4883-9e1f-ed203b1135ad/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls"} Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-QkyrBosDDjM31y1V-qo2mtw-t500x500.jpg","uri":"soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":16000,"duration":0,"service":"soundcloud","artist":"osho.mo","album":"SoundCloud Track","name":"Thank You God","title":"Thank You God","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:40 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:40 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:40 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.609+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=16000 volume=95 Aug 30 16:56:40 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:40.609+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:40 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:40 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:40 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 11 error: Cancellation (0x8) Aug 30 16:56:40 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3206858] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:41 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:41 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f243fe50] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:41 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:41 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:56:41 primo kernel: spdif_a is set to disable Aug 30 16:56:41 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:41 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:56:41 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:56:41 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:56:41 primo volumio[10717]: info: camilladsp respawn in 400 ms (attempt 3/10) Aug 30 16:56:41 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 3 error: Cancellation (0x8) Aug 30 16:56:41 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:56:41 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:56:41 primo kernel: aml_tdm_open Aug 30 16:56:41 primo kernel: Not init audio effects Aug 30 16:56:41 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:56:41 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:41 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:41 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:41 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:56:41 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:56:41 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:56:41 primo kernel: pcm_mode(1) Aug 30 16:56:41 primo kernel: sysclk(11289600) Aug 30 16:56:41 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:56:41 primo kernel: bclk(2822400) Aug 30 16:56:41 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:56:41 primo kernel: lrclk(44100) Aug 30 16:56:41 primo kernel: tx_mask(0x3) Aug 30 16:56:41 primo kernel: rx_mask(0x3) Aug 30 16:56:41 primo kernel: slots(2) Aug 30 16:56:41 primo kernel: slot_width(32) Aug 30 16:56:41 primo kernel: lane_mask_in(0x2) Aug 30 16:56:41 primo kernel: lane_mask_out(0x1) Aug 30 16:56:41 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:56:41 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:56:41 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:56:41 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:41 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:41 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:41 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:56:41 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:56:41 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:56:41 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:56:41 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:56:41 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:56:41 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:41 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:56:41 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 5 error: Cancellation (0x8) Aug 30 16:56:42 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:56:42 primo kernel: spdif_a is set to enable Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 7 error: Cancellation (0x8) Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:42 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:42 primo volumio[10717]: verbose: CURRENT POSITION 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::play index 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.682+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.682+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.683+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.682+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:56:42 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:56:42 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.695+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.696+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:42 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:42 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:42 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:42 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:42 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:42 primo volumio[10717]: info: sendMpdCommand stop took 32 milliseconds Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/10bb32b7-46ef-4ba3-8a10-307e7db99bf7/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ce3b2f83-7feb-4883-9e1f-ed203b1135ad/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/ced26047-aada-47fd-863e-91fd22be3d4b/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2366265569/57e1310f-f906-429a-b09f-5542727c6d46/stream/hls"} Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-QkyrBosDDjM31y1V-qo2mtw-t500x500.jpg","uri":"soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":0,"duration":0,"service":"soundcloud","artist":"osho.mo","album":"SoundCloud Track","name":"Thank You God","title":"Thank You God","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:42 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:42 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:42 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.875+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:42 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:42.876+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:42 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:42 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:42 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 9 error: Cancellation (0x8) Aug 30 16:56:42 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f244c148] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:43 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3935cf8] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:43 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f1bfb108] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:43 primo kernel: asoc-aml-card auge_sound: tdm playback stop Aug 30 16:56:43 primo kernel: spdif_a is set to disable Aug 30 16:56:43 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:43 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Aug 30 16:56:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Aug 30 16:56:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Aug 30 16:56:43 primo volumio[10717]: info: camilladsp respawn in 800 ms (attempt 4/10) Aug 30 16:56:43 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3935cf8] http demux error: local stream 3 error: Cancellation (0x8) Aug 30 16:56:43 primo volumio[10717]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 16:56:43 primo volumio[10717]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 16:56:44 primo volumio[10717]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 16:56:44 primo volumio[10717]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioPlay Aug 30 16:56:44 primo volumio[10717]: verbose: UNSET VOLATILE: Service: soundcloud Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Volatile state unset, stopping playback (if any)... Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":0,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:44 primo volumio[10717]: verbose: CURRENT POSITION 3 Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::syncState stateService stop Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::syncState currentStatus pause Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::play index 4 Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.264+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.264+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.264+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.264+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::updateTrackBlock Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrackBlock Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::stPlaybackTimer Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::serviceStop Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::serviceStop Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 3 Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 16:56:44 primo volumio[10717]: info: ControllerMpd::stop Aug 30 16:56:44 primo volumio[10717]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.276+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_STOPPED positionMs=0 volume=95 Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.277+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2366265569@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title="Thank You God" Aug 30 16:56:44 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:44 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:44 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:44 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:44 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:44 primo volumio[10717]: info: sendMpdCommand stop took 36 milliseconds Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::play index undefined Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 4 Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 4 Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=2367063311@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/dc131322-28c8-442d-b2b2-dc0d686b887a/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/ee4b38d8-3c17-47ce-9236-518d1daa8d49/stream/hls","preset":"aac_96k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"lq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/6ade5fb2-b7c7-4c3f-a44e-db06946e6fb1/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/f10deb91-b5f5-46e9-81f9-d562e81ff43d/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/f10deb91-b5f5-46e9-81f9-d562e81ff43d/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:2367063311/dc131322-28c8-442d-b2b2-dc0d686b887a/stream/hls"} Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3935cf8] http demux error: local stream 5 error: Cancellation (0x8) Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 4 Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: CorePlayQueue::getTrack 4 Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Stopping playback by current service... Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioStop Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::stop Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Setting ourselves as the current service... Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-EefJcrnbPnUdAbjR-f5xjzg-t500x500.jpg","uri":"soundcloud/track@trackId=2367063311@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D","seek":0,"duration":0,"service":"soundcloud","artist":"Elfrani Vane","album":"SoundCloud Track","name":"he-sees-the-sacrifice.mp3","title":"he-sees-the-sacrifice.mp3","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":95,"mute":false,"disableVolumeControl":false} Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::servicePushState Aug 30 16:56:44 primo volumio[10717]: info: CoreStateMachine::pushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioPushState Aug 30 16:56:44 primo volumio[10717]: info: CoreCommandRouter::volumioGetState Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output update for this device Aug 30 16:56:44 primo volumio[10717]: info: MRS: Pushing multiroomSync output Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.413+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" state=STATUS_PAUSED positionMs=0 volume=95 Aug 30 16:56:44 primo volumio5-onboarding[3951]: time=2026-08-30T16:56:44.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.150:52908 @ 0x2148f00" id="soundcloud/track@trackId=2367063311@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1780168443%7D" title=he-sees-the-sacrifice.mp3 Aug 30 16:56:44 primo volumio[10717]: info: FusionDsp - Volumio is not playing Aug 30 16:56:44 primo volumio[10717]: info: FusionDsp - Clipped samples monitor stopped Aug 30 16:56:44 primo volumio[10717]: info: Updating RAAT Signal Path Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3935cf8] http demux error: local stream 7 error: Cancellation (0x8) Aug 30 16:56:44 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f19f9940] adaptive demux: Changing stream format Unknown -> MP4 Aug 30 16:56:45 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f3932cd8] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Aug 30 16:56:45 primo volumio[10717]: info: [soundcloud] [vlc] (PID: 11317) [f19f9940] http demux error: local stream 1 error: Cancellation (0x8) Aug 30 16:56:45 primo volumio[10717]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:56:45 primo volumio[10717]: Error: Operation timeout Aug 30 16:56:45 primo volumio[10717]: at Timeout._onTimeout (/data/plugins/music_service/soundcloud/node_modules/volumio-ext-players/dist/vlc/VLCControl.js:28:20) Aug 30 16:56:45 primo volumio[10717]: at listOnTimeout (node:internal/timers:573:17) Aug 30 16:56:45 primo volumio[10717]: at process.processTimers (node:internal/timers:514:7) Aug 30 16:56:45 primo volumio[10717]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:56:45 primo kernel: aml_tdm_open Aug 30 16:56:45 primo kernel: Not init audio effects Aug 30 16:56:45 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Aug 30 16:56:45 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:45 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:45 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:45 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501eea18, id(1), clksel(1) Aug 30 16:56:45 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Aug 30 16:56:45 primo kernel: dump_pcm_setting(ffffffc0501eea18) Aug 30 16:56:45 primo kernel: pcm_mode(1) Aug 30 16:56:45 primo kernel: sysclk(11289600) Aug 30 16:56:45 primo kernel: sysclk_bclk_ratio(4) Aug 30 16:56:45 primo kernel: bclk(2822400) Aug 30 16:56:45 primo kernel: bclk_lrclk_ratio(64) Aug 30 16:56:45 primo kernel: lrclk(44100) Aug 30 16:56:45 primo kernel: tx_mask(0x3) Aug 30 16:56:45 primo kernel: rx_mask(0x3) Aug 30 16:56:45 primo kernel: slots(2) Aug 30 16:56:45 primo kernel: slot_width(32) Aug 30 16:56:45 primo kernel: lane_mask_in(0x2) Aug 30 16:56:45 primo kernel: lane_mask_out(0x1) Aug 30 16:56:45 primo kernel: lane_oe_mask_in(0x0) Aug 30 16:56:45 primo kernel: lane_oe_mask_out(0x0) Aug 30 16:56:45 primo kernel: lane_lb_mask_in(0x0) Aug 30 16:56:45 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Aug 30 16:56:45 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Aug 30 16:56:45 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Aug 30 16:56:45 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Aug 30 16:56:45 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Aug 30 16:56:45 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Aug 30 16:56:45 primo kernel: aml_tdm_prepare(), reset fddr Aug 30 16:56:45 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Aug 30 16:56:45 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Aug 30 16:56:45 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Aug 30 16:56:45 primo kernel: tdm playback mute: 0, lane_cnt = 8 Aug 30 16:56:45 primo kernel: asoc-aml-card auge_sound: tdm playback enable Aug 30 16:56:45 primo kernel: spdif_a is set to enable Aug 30 16:56:46 primo sudo[12247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 16:55' Aug 30 16:56:46 primo sudo[12247]: 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="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun May 17 17:32:08 UTC 2026" VOLUMIO_VERSION="4.158" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="43d420a3aa41c50690ebfe378df38e2b"