-- Logs begin at Fri 2026-03-27 20:23:40 CET, end at Fri 2026-03-27 21:29:25 CET. -- Mar 27 21:29:04 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 27 21:29:04 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 21:29:05 primo volumio[3391]: info: Preload queue cleared Mar 27 21:29:15 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 27 21:29:16 primo volumio[3391]: info: Preload queue cleared Mar 27 21:29:20 primo volumio[3391]: info: Preload queue cleared Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::ClearQueue Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::stop Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::stPlaybackTimer Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::updateTrackBlock Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrackBlock Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrack 5 Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrack 5 Mar 27 21:29:20 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:20 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::serviceStop Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrack 5 Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::serviceStop Mar 27 21:29:20 primo volumio[3391]: info: [1774643360584] ControllerTidal::stop Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 27 21:29:20 primo volumio[3391]: info: ControllerMpd::stop Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::clearPlayQueue Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::saveQueue Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioPushQueue Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::addQueueItems Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::addQueueItems Mar 27 21:29:20 primo volumio[3391]: info: Preload queue cleared Mar 27 21:29:20 primo volumio[3391]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320 Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 27 21:29:20 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:20.590+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_STOPPED positionMs=0 volume=20 Mar 27 21:29:20 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:20.590+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioPushQueue Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::saveQueue Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::updateTrackBlock Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrackBlock Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioPlay Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::play index 0 Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::stop Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::play index undefined Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:20 primo volumio[3391]: info: CoreStateMachine::startPlaybackTimer Mar 27 21:29:20 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 21:29:20 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 27 21:29:20 primo volumio[3391]: info: [1774643360610] ControllerWebradio::clearAddPlayTrack Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand stop Mar 27 21:29:20 primo kernel: asoc-aml-card auge_sound: tdm playback stop Mar 27 21:29:20 primo kernel: spdif_a is set to disable Mar 27 21:29:20 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 27 21:29:20 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Mar 27 21:29:20 primo kernel: tdm playback mute: 1, lane_cnt = 8 Mar 27 21:29:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Mar 27 21:29:20 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:20 primo volumio[3391]: info: Mar 27 21:29:20 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:20 primo volumio[3391]: info: sendMpdCommand stop took 44 milliseconds Mar 27 21:29:20 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:20 primo volumio[3391]: info: sendMpdCommand stop took 19 milliseconds Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand clear Mar 27 21:29:20 primo volumio[3391]: info: Mar 27 21:29:20 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:20 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:20 primo volumio[3391]: info: sendMpdCommand status took 4 milliseconds Mar 27 21:29:20 primo volumio[3391]: info: sendMpdCommand clear took 2 milliseconds Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" Mar 27 21:29:20 primo volumio[3391]: info: Mar 27 21:29:20 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:20 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:20 primo volumio[3391]: info: Mar 27 21:29:20 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:20 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:20 primo volumio[3391]: error: updateQueue error: null Mar 27 21:29:20 primo volumio[3391]: info: ------------------------------ 14ms Mar 27 21:29:20 primo volumio[3391]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 27 21:29:20 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:20 primo volumio[3391]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 27 21:29:20 primo volumio[3391]: info: ------------------------------ 20ms Mar 27 21:29:20 primo volumio[3391]: info: MCU Signalled Playback Inactive Mar 27 21:29:21 primo volumio[3391]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99568320" took 1287 milliseconds Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 27 21:29:21 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand play Mar 27 21:29:21 primo volumio[3391]: info: Mar 27 21:29:21 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:21 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:21 primo volumio[3391]: info: sendMpdCommand status took 1285 milliseconds Mar 27 21:29:21 primo volumio[3391]: info: sendMpdCommand status took 1282 milliseconds Mar 27 21:29:21 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:21 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:21 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:21 primo volumio[3391]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 27 21:29:21 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::syncState stateService stop Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus stop Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio[3391]: info: No code Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:21 primo volumio[3391]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 27 21:29:21 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::syncState stateService stop Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus stop Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio[3391]: info: No code Mar 27 21:29:21 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:21 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:21 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.968+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=1311 volume=20 Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.969+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.970+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=1311 volume=20 Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.970+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.972+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=1311 volume=20 Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.972+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.973+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.974+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=1311 volume=20 Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.975+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:21.975+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=tidal://song/91388697 title="Kings And Queens" Mar 27 21:29:21 primo volumio[3391]: info: ------------------------------ 1352ms Mar 27 21:29:21 primo volumio[3391]: info: ------------------------------ 1352ms Mar 27 21:29:21 primo volumio[3391]: info: Mar 27 21:29:21 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:21 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:21 primo volumio[3391]: info: Mar 27 21:29:21 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:21 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:22 primo volumio[3391]: info: sendMpdCommand play took 77 milliseconds Mar 27 21:29:22 primo volumio[3391]: info: ------------------------------ 77ms Mar 27 21:29:22 primo volumio[3391]: info: ------------------------------ 5ms Mar 27 21:29:22 primo volumio[3391]: info: ------------------------------ 5ms Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:22 primo volumio[3391]: info: MCU Signalled Playback Active Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo kernel: aml_tdm_open Mar 27 21:29:24 primo kernel: Not init audio effects Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Mar 27 21:29:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 27 21:29:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 27 21:29:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Mar 27 21:29:24 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc0501e3618, id(1), clksel(1) Mar 27 21:29:24 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Mar 27 21:29:24 primo kernel: dump_pcm_setting(ffffffc0501e3618) Mar 27 21:29:24 primo kernel: pcm_mode(1) Mar 27 21:29:24 primo kernel: sysclk(11289600) Mar 27 21:29:24 primo kernel: sysclk_bclk_ratio(4) Mar 27 21:29:24 primo kernel: bclk(2822400) Mar 27 21:29:24 primo kernel: bclk_lrclk_ratio(64) Mar 27 21:29:24 primo kernel: lrclk(44100) Mar 27 21:29:24 primo kernel: tx_mask(0x3) Mar 27 21:29:24 primo kernel: rx_mask(0x3) Mar 27 21:29:24 primo kernel: slots(2) Mar 27 21:29:24 primo kernel: slot_width(32) Mar 27 21:29:24 primo kernel: lane_mask_in(0x2) Mar 27 21:29:24 primo kernel: lane_mask_out(0x1) Mar 27 21:29:24 primo kernel: lane_oe_mask_in(0x0) Mar 27 21:29:24 primo kernel: lane_oe_mask_out(0x0) Mar 27 21:29:24 primo kernel: lane_lb_mask_in(0x0) Mar 27 21:29:24 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Mar 27 21:29:24 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Mar 27 21:29:24 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Mar 27 21:29:24 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Mar 27 21:29:24 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Mar 27 21:29:24 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Mar 27 21:29:24 primo kernel: aml_tdm_prepare(), reset fddr Mar 27 21:29:24 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Mar 27 21:29:24 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Mar 27 21:29:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 27 21:29:24 primo kernel: tdm playback mute: 0, lane_cnt = 8 Mar 27 21:29:24 primo kernel: aml_tdm_prepare(), reset fddr Mar 27 21:29:24 primo kernel: spdif_a fifo ctrl, frddr:0 type:4, 24 bits, chmask 0x3, swap 0x10 Mar 27 21:29:24 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Mar 27 21:29:24 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Mar 27 21:29:24 primo kernel: tdm playback mute: 0, lane_cnt = 8 Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 10 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 18 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 16 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 9 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 4 milliseconds Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus stop Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 34ms Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 14 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"(#1 - 5791/32000) Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"dance.mp3","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.202+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.203+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.204+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.205+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.206+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.207+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.208+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.209+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.209+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.209+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.210+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.210+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.211+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.211+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.212+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.213+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.211+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=0 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.213+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="Dance Wave!" Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 143ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 143ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 138ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 133ms Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo kernel: asoc-aml-card auge_sound: tdm playback enable Mar 27 21:29:24 primo kernel: spdif_a is set to enable Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 124ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 121 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 124ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 122 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 10ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 8 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 8ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 7 milliseconds Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces system playlist update Mar 27 21:29:24 primo volumio[3391]: info: Ignoring MPD Status Update Mar 27 21:29:24 primo volumio[3391]: info: Mar 27 21:29:24 primo volumio[3391]: ---------------------------- MPD announces state update: player Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::getState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand status Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 74 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 73ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 72 milliseconds Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 71ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand status took 69 milliseconds Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseState Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1229,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1355,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1355,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.414+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.415+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.415+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.416+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.416+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.415+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.418+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.418+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.419+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.419+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.420+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.421+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.422+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.424+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=144 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.425+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.429+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 311ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 197ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 196ms Mar 27 21:29:24 primo volumio[3391]: info: sendMpdCommand playlistinfo took 112 milliseconds Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: verbose: ControllerMpd::parseTrackInfo Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: ControllerMpd::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::servicePushState Mar 27 21:29:24 primo volumio[3391]: info: CorePlayQueue::getTrack 0 Mar 27 21:29:24 primo volumio[3391]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"All about Dance from 2000 till today!","artist":"Dance Wave!","album":null,"uri":"https://dancewave.online:443/dance.mp3","trackType":"mp3"} Mar 27 21:29:24 primo volumio[3391]: verbose: CURRENT POSITION 0 Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState stateService play Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::syncState currentStatus play Mar 27 21:29:24 primo volumio[3391]: info: Received an update from plugin. extracting info from payload Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio[3391]: info: CoreStateMachine::pushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioPushState Mar 27 21:29:24 primo volumio[3391]: info: CoreCommandRouter::volumioGetState Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output update for this device Mar 27 21:29:24 primo volumio[3391]: info: MRS: Pushing multiroomSync output Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.492+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=342 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.493+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=342 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.494+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" state=STATUS_PLAYING positionMs=342 volume=20 Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.495+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio5-onboarding[3917]: time=2026-03-27T21:29:24.496+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.2.108:33540 @ 0x2b4a480" id=https://dancewave.online:443/dance.mp3 title="All about Dance from 2000 till today!" Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 251ms Mar 27 21:29:24 primo volumio[3391]: info: ------------------------------ 249ms Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Signalling Playback active due to playback status change Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:24 primo volumio[3391]: info: Updating RAAT Signal Path Mar 27 21:29:25 primo volumio[3391]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:29:25 primo volumio[3391]: TypeError: Cannot read property 'slice' of null Mar 27 21:29:25 primo volumio[3391]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 27 21:29:25 primo volumio[3391]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 27 21:29:25 primo volumio[3391]: at Object.onceWrapper (events.js:520:26) Mar 27 21:29:25 primo volumio[3391]: at ClientRequest.emit (events.js:400:28) Mar 27 21:29:25 primo volumio[3391]: at HTTPParser.parserOnIncomingClient [as onIncoming] (_http_client.js:647:27) Mar 27 21:29:25 primo volumio[3391]: at HTTPParser.parserOnHeadersComplete (_http_common.js:127:17) Mar 27 21:29:25 primo volumio[3391]: at TLSSocket.socketOnData (_http_client.js:515:22) Mar 27 21:29:25 primo volumio[3391]: at TLSSocket.emit (events.js:400:28) Mar 27 21:29:25 primo volumio[3391]: at addChunk (internal/streams/readable.js:293:12) Mar 27 21:29:25 primo volumio[3391]: at readableAddChunk (internal/streams/readable.js:267:9) Mar 27 21:29:25 primo volumio[3391]: at TLSSocket.Readable.push (internal/streams/readable.js:206:10) Mar 27 21:29:25 primo volumio[3391]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:188:23) Mar 27 21:29:25 primo volumio[3391]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 21:29:25 primo sudo[19075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 21:28 Mar 27 21:29:25 primo sudo[19075]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 12:16:07 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="88686576587e39571d6cf25f3920fbc0"