Oct 03 13:05:02 primo volumio[3533]: info: MCU Signalled Sleep Mode Disabled Oct 03 13:05:02 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:02 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:02 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:02 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:02 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:02 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:02 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:02 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:02 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:02 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:02 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:02 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:02 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:02 primo sudo[31826]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Oct 03 13:05:02 primo sudo[31826]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:05:02 primo sudo[31826]: pam_unix(sudo:session): session closed for user root Oct 03 13:05:02 primo volumio[3533]: error: Serial API: Failed to decode command: LEDCOLOR, message: 2 Oct 03 13:05:02 primo sudo[31829]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Oct 03 13:05:02 primo sudo[31829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:05:02 primo sudo[31829]: pam_unix(sudo:session): session closed for user root Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.468+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.0.26:32828 Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.541+02:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-15.11021ms platform=PLATFORM_ANDROID version=6.260930.0 Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.541+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.0.26:32828 @ 0x200a6f0" latency=-16.866584ms timeout=20s Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.541+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" Oct 03 13:05:27 primo volumio[3533]: info: Received Get System Info Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:05:27 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.554+02:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" name=Primo Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.555+02:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" language=en Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.558+02:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" timezone=Europe/Belgrade Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.559+02:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" available=true connected=true macAddress=02:00:00:28:20:01 ip4Address=192.168.0.24/24 ip6Address= Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.561+02:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.561+02:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" setupComplete=true Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.598+02:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" selectedOutputId=0,2 Oct 03 13:05:27 primo volumio[3533]: info: Received Get System Info Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:05:27 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.617+02:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" currentVersion=4.158 latestVersion=4.158 Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.617+02:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" status=UPDATE_STATUS_NONE progress=0 Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.617+02:00 level=INFO msg="emitting user changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" userId= Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.617+02:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" providers=3 Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.620+02:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" plugins=0 Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.624+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=163288 volume=99 Oct 03 13:05:27 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:27.624+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:05:27 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:05:27 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24:3000 from 192.168.0.26 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 13:05:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 13:05:37 primo volumio[3533]: info: Pushing metadata Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:37 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:37 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:37.779+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=163288 volume=99 Oct 03 13:05:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:37.780+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:37.781+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=163288 volume=99 Oct 03 13:05:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:37.781+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:37 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:37 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:39 primo vtcs[4028]: [start:106] Entering Oct 03 13:05:39 primo vtcs[4028]: [start:113] Reopening PCM device... Oct 03 13:05:39 primo kernel: aml_spdif_open Oct 03 13:05:39 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:39 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:39 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:39 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:05:39 primo kernel: set normal 512 fs /4 fs Oct 03 13:05:39 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:05:39 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:05:39 primo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 03 13:05:39 primo kernel: set normal 512 fs /4 fs Oct 03 13:05:39 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:05:39 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:05:39 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:05:39 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:05:39 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:39 primo vtcs[4028]: [start:115] PCM opened successfully. Oct 03 13:05:39 primo vtcs[4028]: [start:120] Exiting Oct 03 13:05:39 primo vtcs[4028]: [feedThread:223] Entering Oct 03 13:05:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:39.880+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=8630 volume=99 Oct 03 13:05:39 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:05:39 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:05:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:39.880+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:39 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:39 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:39 primo volumio[3533]: info: MCU Signalled Playback Active Oct 03 13:05:40 primo vtcs[4028]: [2026-10-03 13:05:40.278] [tisoc] [warning] [http_stream_fetcher.cpp:119] Http request failed with status code 0. Will not retry. Oct 03 13:05:40 primo vtcs[4028]: [2026-10-03 13:05:40.278] [tisoc] [error] [audio_worker.cpp:179] Max connection attempts reached, url: https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidkMjg1MTY2NTc4ZTU0YzBjMDYyOTc3OTBiYTBjNTczN182MS5tcDQ/0.flac?token=1790979722~NmJiNzJjOTY5NzM2NGNkZjA5MWI3N2NiMmM3ZjE3Y2FiNTFhMWIzZQ==, code: 0, message: Http request failed with status code 0. Will not retry. Oct 03 13:05:40 primo vtcs[4028]: [close:85] Entering Oct 03 13:05:40 primo vtcs[4028]: [feedThread:276] Exiting Oct 03 13:05:40 primo vtcs[4028]: [close:100] Exiting Oct 03 13:05:40 primo volumio[3533]: info: Unknown command: notifyPlaybackError Oct 03 13:05:40 primo kernel: spdif_a keep clk continuous Oct 03 13:05:40 primo kernel: aml_spdif_close Oct 03 13:05:40 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:05:40 primo vtcs[4028]: [close:85] Entering Oct 03 13:05:40 primo vtcs[4028]: [close:100] Exiting Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:40 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:40 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:40.316+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:40.316+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:40.316+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:05:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:40.317+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="Evil Ways" Oct 03 13:05:40 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:40 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:40 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:40 primo volumio[3533]: info: MCU Signalled Playback Inactive Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:05:50 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:05:50 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24:3000 from 192.168.0.26 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 13:05:50 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 13:05:54 primo vtcs[4028]: [2026-10-03 13:05:54.559] [tisoc] [warning] [PlaybackControllerImpl.cpp:220] Ignore play request because player state is already PLAYING. state_=5, playstate_=1, player_state_=0 Oct 03 13:05:57 primo vtcs[4028]: [close:85] Entering Oct 03 13:05:57 primo vtcs[4028]: [close:100] Exiting Oct 03 13:05:57 primo vtcs[4028]: [2026-10-03 13:05:57.878] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Oct 03 13:05:57 primo volumio[3533]: info: Pushing metadata Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:57 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:57 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:57 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:57 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:57 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:57 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.907+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.907+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.908+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.908+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.909+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.909+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.909+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:57 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:57.909+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:57 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:57 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:57 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:57 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:57 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:58 primo volumio[3533]: info: MCU Signalled Playback Active Oct 03 13:05:58 primo vtcs[4028]: [close:85] Entering Oct 03 13:05:58 primo vtcs[4028]: [close:100] Exiting Oct 03 13:05:58 primo volumio[3533]: info: Pushing metadata Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:58 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.473+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.473+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:58 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:58 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:58 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:58 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:58 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.501+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.501+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.501+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.502+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.502+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:58 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:58.503+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:58 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:58 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:58 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:58 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:58 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:58 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:58 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:58 primo volumio[3533]: info: MCU Signalled Playback Inactive Oct 03 13:05:58 primo volumio[3533]: info: MCU Signalled Playback Active Oct 03 13:05:59 primo vtcs[4028]: [close:85] Entering Oct 03 13:05:59 primo vtcs[4028]: [close:100] Exiting Oct 03 13:05:59 primo vtcs[4028]: [open:50] Opening with sample rate: 44100, type: 0 Oct 03 13:05:59 primo vtcs[4028]: [start:106] Entering Oct 03 13:05:59 primo vtcs[4028]: [start:113] Reopening PCM device... Oct 03 13:05:59 primo kernel: aml_spdif_open Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:59 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:59 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:05:59 primo kernel: set normal 512 fs /4 fs Oct 03 13:05:59 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:05:59 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:05:59 primo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 03 13:05:59 primo kernel: set normal 512 fs /4 fs Oct 03 13:05:59 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:05:59 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:05:59 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:05:59 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:05:59 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:05:59 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:05:59 primo vtcs[4028]: [start:115] PCM opened successfully. Oct 03 13:05:59 primo vtcs[4028]: [start:120] Exiting Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.022+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.022+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:59 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:59 primo vtcs[4028]: [feedThread:223] Entering Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:59 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:59 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:59 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:59 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:59 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.061+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.062+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:59 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:59 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 03 13:05:59 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:05:59 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:05:59 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:05:59 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.428+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=524 volume=99 Oct 03 13:05:59 primo volumio5-onboarding[3791]: time=2026-10-03T13:05:59.428+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:05:59 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:05:59 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:06 primo vtcs[4028]: [close:85] Entering Oct 03 13:06:06 primo volumio[3533]: info: PlaybackInactive Oct 03 13:06:06 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Oct 03 13:06:06 primo kernel: spdif_a keep clk continuous Oct 03 13:06:06 primo kernel: aml_spdif_close Oct 03 13:06:06 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:06:06 primo volumio[3533]: info: Device Session is_active: true Oct 03 13:06:06 primo vtcs[4028]: [feedThread:276] Exiting Oct 03 13:06:06 primo vtcs[4028]: [close:100] Exiting Oct 03 13:06:06 primo volumio[3533]: info: DeviceInactive Oct 03 13:06:06 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:06 primo volumio[3533]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 03 13:06:06 primo volumio[3533]: info: unSetVolatile called Oct 03 13:06:06 primo volumio[3533]: info: Relinquishing Volumio State to another service Oct 03 13:06:06 primo vtcs[4028]: [2026-10-03 13:06:06.920] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 13:06:06 primo vtcs[4028]: [2026-10-03 13:06:06] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: Currently active: mpd Oct 03 13:06:10 primo volumio[3533]: info: Stopping currently active service Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioStop Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::stop Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::updateTrackBlock Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrackBlock Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::serviceStop Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: ControllerMpd::stop Oct 03 13:06:10 primo volumio[3533]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.507+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.507+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:10 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:10 primo volumio[3533]: info: sendMpdCommand stop took 20 milliseconds Oct 03 13:06:10 primo volumio[3533]: info: TidalConnect Active Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:10 primo volumio[3533]: info: Currently active: mpd Oct 03 13:06:10 primo volumio[3533]: info: Setting Volatile state to tidalconnect Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:10 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:10 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:10 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.538+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.539+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.540+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:06:10 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:10.541+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:10 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:10 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:10 primo volumio[3533]: info: MCU Signalled Playback Inactive Oct 03 13:06:10 primo vtcs[4028]: [2026-10-03 13:06:10.858] [tisoc] [warning] [PlaybackControllerImpl.cpp:498] Illegal state:1 Oct 03 13:06:11 primo vtcs[4028]: [2026-10-03 13:06:11.385] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Oct 03 13:06:11 primo volumio[3533]: info: Pushing metadata Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=0 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.416+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.417+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.418+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.419+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.420+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: MCU Signalled Playback Active Oct 03 13:06:11 primo vtcs[4028]: [close:85] Entering Oct 03 13:06:11 primo vtcs[4028]: [close:100] Exiting Oct 03 13:06:11 primo volumio[3533]: info: Pushing metadata Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.949+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.950+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:11 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.958+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PAUSED positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.958+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.959+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.961+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:11 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.967+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27506 volume=99 Oct 03 13:06:11 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:11.967+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:11 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:11 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:11 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:11 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:12 primo volumio[3533]: info: MCU Signalled Playback Inactive Oct 03 13:06:12 primo volumio[3533]: info: MCU Signalled Playback Active Oct 03 13:06:12 primo vtcs[4028]: [close:85] Entering Oct 03 13:06:12 primo vtcs[4028]: [close:100] Exiting Oct 03 13:06:12 primo vtcs[4028]: [open:50] Opening with sample rate: 44100, type: 0 Oct 03 13:06:12 primo vtcs[4028]: [start:106] Entering Oct 03 13:06:12 primo vtcs[4028]: [start:113] Reopening PCM device... Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:12 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:12 primo kernel: aml_spdif_open Oct 03 13:06:12 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:06:12 primo kernel: set normal 512 fs /4 fs Oct 03 13:06:12 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:06:12 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:06:12 primo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 03 13:06:12 primo kernel: set normal 512 fs /4 fs Oct 03 13:06:12 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:06:12 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:06:12 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:06:12 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:06:12 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:06:12 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:12 primo vtcs[4028]: [start:115] PCM opened successfully. Oct 03 13:06:12 primo vtcs[4028]: [start:120] Exiting Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:12 primo vtcs[4028]: [feedThread:223] Entering Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.393+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27506 volume=99 Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.394+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:12 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.407+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=0 volume=99 Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.407+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:12 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:12 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:12 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:12 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:12 primo vtcs[4028]: [2026-10-03 13:06:12.478] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:12 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.511+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=27505 volume=99 Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.511+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:12 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:12 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:12 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::servicePushState Oct 03 13:06:12 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:12 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:12 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.786+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_PLAYING positionMs=28010 volume=99 Oct 03 13:06:12 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:12.786+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title="I'm Waiting For You" Oct 03 13:06:12 primo volumio[3533]: info: Signalling Playback active due to playback status change Oct 03 13:06:12 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:06:21 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:06:21 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24:3000 from 192.168.0.26 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 03 13:06:21 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 03 13:06:22 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:22.742+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.26:32828 @ 0x200a6f0" latency=-10.893161ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 03 13:06:22 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:22.761+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s Oct 03 13:06:22 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:22.872+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=http://pushupdates.volumio.org duration=109.980899ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.019+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=257.558267ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.213+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://securetoken.googleapis.com duration=448.500835ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.213+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://www.googleapis.com duration=448.780878ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.220+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://functions.volumio.cloud duration=457.056199ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.221+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://functions.volumio.cloud duration=456.694239ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.270+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=507.113173ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.276+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=513.548905ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.302+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=538.748662ms Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.331+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=http://cddb.volumio.org duration=568.783105ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 13:06:23 primo sudo[32000]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 13:06:23 primo sudo[32000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:23 primo sudo[32002]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 13:06:23 primo sudo[32002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:23 primo sudo[32000]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:23 primo sudo[32002]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:23 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24 from 192.168.0.26 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.653+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=http://plugins.volumio.org duration=889.479559ms Oct 03 13:06:23 primo sudo[32008]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 13:06:23 primo sudo[32008]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:23 primo sudo[32010]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 13:06:23 primo sudo[32010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:23 primo sudo[32008]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:23 primo sudo[32010]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.737+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://google.com duration=973.066113ms Oct 03 13:06:23 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24 from 192.168.0.26 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 03 13:06:23 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:23.743+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-24.19408ms timeout=10s endpoint=https://database.volumio.cloud duration=977.988297ms Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 13:06:23 primo volumio[3533]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 03 13:06:23 primo volumio[3533]: info: Received Get System Info Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:06:23 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:23 primo volumio[3533]: info: Listing playlists Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 13:06:23 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:06:25 primo volumio[3533]: info: Received Get System Info Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:06:25 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:25 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: Retrieving Cloud Streaming UI Oct 03 13:06:27 primo volumio[3533]: info: Getting Tidal Cloud Configuration Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: Getting Qobuz Cloud Configuration Oct 03 13:06:27 primo volumio[3533]: info: Asking plugin for UI Config Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: Getting Spotify Cloud Configuration Oct 03 13:06:27 primo volumio[3533]: info: Asking plugin for UI Config Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: Saving Spotify Acccount Oct 03 13:06:27 primo volumio[3533]: info: Got it Oct 03 13:06:27 primo volumio[3533]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 03 13:06:27 primo volumio[3533]: info: Got Tidal Cloud Configuration Oct 03 13:06:27 primo volumio[3533]: info: Got it Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 13:06:27 primo volumio[3533]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 13:06:28 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 13:06:28 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.643+02:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.838804ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.654+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.673+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=18.569068ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.696+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=http://pushupdates.volumio.org duration=38.46864ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.698+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=41.874194ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.702+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=45.638165ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.778+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://www.googleapis.com duration=122.67832ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.817+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://functions.volumio.cloud duration=161.072875ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.821+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=165.892977ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.825+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://database.volumio.cloud duration=167.655941ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.828+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://functions.volumio.cloud duration=171.026328ms Oct 03 13:06:30 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:30.838+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://securetoken.googleapis.com duration=182.398702ms Oct 03 13:06:30 primo sudo[32016]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 13:06:30 primo sudo[32016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:30 primo sudo[32018]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 13:06:30 primo sudo[32018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:30 primo sudo[32016]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:30 primo sudo[32018]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:30 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24 from 192.168.0.26 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:31 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:31.009+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=http://cddb.volumio.org duration=351.960942ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 13:06:31 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:31.011+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=http://plugins.volumio.org duration=353.686615ms Oct 03 13:06:31 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:31.049+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.26:32828 @ 0x200a6f0" latency=-25.485338ms timeout=10s endpoint=https://google.com duration=394.239179ms Oct 03 13:06:31 primo sudo[32024]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 03 13:06:31 primo sudo[32024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:31 primo sudo[32026]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 03 13:06:31 primo sudo[32026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:31 primo sudo[32024]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:31 primo sudo[32026]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:31 primo volumio[3533]: verbose: New Socket.io Connection to 192.168.0.24 from 192.168.0.26 UA: Mozilla/5.0 (Linux; Android 16; SM-S911B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.57 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::volumioGetVisibleSources Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 03 13:06:31 primo volumio[3533]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 03 13:06:31 primo volumio[3533]: info: Received Get System Info Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:06:31 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:31 primo volumio[3533]: info: Listing playlists Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 03 13:06:31 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 03 13:06:33 primo volumio[3533]: info: Received Get System Info Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 13:06:33 primo volumio[3533]: info: Discovery: Getting this device information Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:33 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:34 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 13:06:37 primo volumio[3533]: info: Preparing to save Alsa Options, stopping services first Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioPause Oct 03 13:06:37 primo volumio[3533]: info: CoreStateMachine::pause Oct 03 13:06:37 primo volumio[3533]: info: CoreStateMachine::stPlaybackTimer Oct 03 13:06:37 primo volumio[3533]: info: CoreStateMachine::servicePause Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::servicePause Oct 03 13:06:37 primo volumio[3533]: info: Received pause Oct 03 13:06:37 primo volumio[3533]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Outputs"}} Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:37 primo vtcs[4028]: [pause:147] Entering Oct 03 13:06:37 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Oct 03 13:06:37 primo kernel: spdif_a keep clk continuous Oct 03 13:06:37 primo kernel: aml_spdif_close Oct 03 13:06:37 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:06:37 primo vtcs[4028]: [feedThread:276] Exiting Oct 03 13:06:37 primo vtcs[4028]: [pause:161] Exiting Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:37 primo volumio[3533]: info: Setting mixer Audio hdmi-out mute for card Analog Outputs Oct 03 13:06:37 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:37 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:06:37 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:37 primo volumio[3533]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 03 13:06:37 primo volumio[3533]: info: unSetVolatile called Oct 03 13:06:37 primo volumio[3533]: info: Relinquishing Volumio State to another service Oct 03 13:06:37 primo vtcs[4028]: [close:85] Entering Oct 03 13:06:37 primo vtcs[4028]: [close:100] Exiting Oct 03 13:06:37 primo vtcs[4028]: [2026-10-03 13:06:37.431] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 13:06:37 primo vtcs[4028]: [2026-10-03 13:06:37.436] [tisoc] [error] [SpkconServer.cpp:383] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 03 13:06:37 primo vtcs[4028]: [2026-10-03 13:06:37.436] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 13:06:37 primo vtcs[4028]: [2026-10-03 13:06:37] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 03 13:06:37 primo sudo[32061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:37 primo sudo[32061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo volumio[3533]: info: Applying Volume Override Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:06:37 primo volumio[3533]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:06:37 primo volumio[3533]: info: Enabling external Volume Control Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:37 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:37 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:37 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:37 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:37 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:37 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:37 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:37 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:06:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:37.519+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:37.519+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:37.520+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:37 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:37.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:37 primo volumio[3533]: info: Preparing to generate the ALSA configuration file Oct 03 13:06:37 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:06:37 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:37 primo sudo[32061]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:37 primo sudo[32068]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:37 primo sudo[32068]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo volumio[3533]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:06:37 primo volumio[3533]: info: Reading ALSA contributions from plugins. Oct 03 13:06:37 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:37 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:37 primo sudo[32074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:37 primo sudo[32074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo volumio[3533]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 03 13:06:37 primo sudo[32068]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:37 primo sudo[32074]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo sudo[32080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:37 primo sudo[32080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:37 primo volumio[3533]: info: MCU Signalled Playback Inactive Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:06:37 primo qobuz-connect[3899]: 20261003 13:06:37.768 [3899.3899] INFO SampleApp: Stopping Local configuration server Oct 03 13:06:37 primo volumio[3533]: info: Asound.conf file written Oct 03 13:06:37 primo sudo[32084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:06:37 primo sudo[32084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo sudo[32084]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:37 primo kernel: Fine tdm clk setting range (0~2000000), 11289593 Oct 03 13:06:37 primo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Oct 03 13:06:37 primo kernel: out of value, fixed it Oct 03 13:06:37 primo kernel: id=0 set inskew=0 Oct 03 13:06:37 primo volumio[3533]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:06:37 primo volumio[3533]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:06:37 primo volumio[3533]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:06:37 primo volumio[3533]: Hardware is initialized using a generic method Oct 03 13:06:37 primo volumio[3533]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Oct 03 13:06:37 primo volumio[3533]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Oct 03 13:06:37 primo volumio[3533]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Oct 03 13:06:37 primo volumio[3533]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Oct 03 13:06:37 primo volumio[3533]: info: Output device has changed, restarting MPD Oct 03 13:06:37 primo volumio[3533]: info: Output device has changed, restarting Shairport Sync Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:37 primo sudo[32089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:06:37 primo sudo[32089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo sudo[32091]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:06:37 primo sudo[32089]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:37 primo sudo[32091]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo volumio[3533]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:06:37 primo volumio[3533]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:37 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:37 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:37 primo kernel: aml_tdm_open Oct 03 13:06:37 primo kernel: Not init audio effects Oct 03 13:06:37 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:37 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:37 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:37 primo volumio[3533]: info: Output device has changed, restarting MPD Oct 03 13:06:37 primo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 03 13:06:37 primo volumio[3533]: info: Output device has changed, restarting Shairport Sync Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:37 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:37 primo sudo[32101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:37 primo sudo[32101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:37 primo sudo[32103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:06:37 primo sudo[32103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32103]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:06:38 primo volumio[3533]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo sudo[32107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:06:38 primo sudo[32107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:38 primo kernel: aml_tdm_open Oct 03 13:06:38 primo kernel: Not init audio effects Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo sudo[32101]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo sudo[32116]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:38 primo sudo[32116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo qobuz-connect[3899]: 20261003 13:06:38.113 [3899.3899] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:06:38 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:06:38 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:38 primo sudo[32120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:38 primo sudo[32120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:38 primo sudo[32121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:38 primo sudo[32080]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo sudo[32132]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:38 primo sudo[32132]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:06:38 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:38 primo sudo[32143]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:38 primo sudo[32143]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32132]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:06:38 primo volumio[3533]: info: MPD Permissions set Oct 03 13:06:38 primo volumio[3533]: info: MPD Permissions set Oct 03 13:06:38 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:06:38 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:06:38 primo volumio[3533]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:06:38 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.310+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.310+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:38 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:06:38 primo volumio[3533]: info: Executing endpoint qc_getconfig Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:06:38 primo sudo[32149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:38 primo sudo[32149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.365 [32127.32127] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:06:38 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.371 [32127.32127] INFO VolumeManager: [0xacc58f58]: Setting new playback volume: 75 Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.371 [32127.32127] INFO VolumeManager: [0xacc58f58]: Setting new mute state: 0 Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.371 [32127.32127] INFO AudioStreamManager: [0xacc58cb0]: Setting new audio download buffer size: 1048576 Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.371 [32127.32127] INFO QobuzConnect: [0xacc59820]: Client initialized! Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.371 [32127.32127] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:38 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:38 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.389 [32127.32127] INFO LocalConfigManager: [0xacc589d8]: Starting Local Configuration server Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.389 [32127.32127] INFO SampleApp: Starting Local configuration server Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.391 [32127.32127] INFO SampleApp: Connected to UNIX socket client 0xacc438f8 Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:38 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.398+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.398+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.399+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.400+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.402+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:38 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:38.403+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:38 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:38 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:38 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:38 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo sudo[32116]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:06:38 primo sudo[32121]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo sudo[32120]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo sudo[32143]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.514 [32127.32127] INFO SampleApp: Playback volume changed: 75 Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:38 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:06:38 primo qobuz-connect[32127]: 20261003 13:06:38.538 [32127.32127] INFO SampleApp: Stopping Local configuration server Oct 03 13:06:38 primo sudo[32170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:38 primo sudo[32170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo volumio[3533]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:06:38 primo volumio[3533]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:38 primo sudo[32177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:38 primo sudo[32177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:38 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:38 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:38 primo volumio[3533]: info: Starting Shairport Sync Oct 03 13:06:38 primo sudo[32177]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: Starting Shairport Sync Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:38 primo sudo[32188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:38 primo sudo[32188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32189]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:06:38 primo sudo[32189]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:06:38 primo sudo[32192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:38 primo sudo[32198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:38 primo sudo[32198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:38 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:06:38 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:06:38 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:38 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:38 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:38 primo sudo[32151]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:06:38 primo sudo[32151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:06:38 primo sudo[32203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:38 primo sudo[32203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32151]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:38 primo sudo[32188]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:38 primo kernel: aml_tdm_open Oct 03 13:06:38 primo kernel: Not init audio effects Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:38 primo sudo[32189]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo sudo[32192]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo kernel: aml_tdm_open Oct 03 13:06:38 primo kernel: Not init audio effects Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:38 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:38 primo volumio[3533]: info: Shairport-Sync Started Oct 03 13:06:38 primo volumio[3533]: info: Shairport-Sync Started Oct 03 13:06:38 primo sudo[32198]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:38 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:38 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:38 primo sudo[32219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:38 primo sudo[32219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:38 primo sudo[32203]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo sudo[32222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:39 primo sudo[32222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:39 primo volumio[3533]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:39 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:39 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:39 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:39 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:39 primo sudo[32219]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:39 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:39 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:39 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:39 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:39 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:39 primo sudo[32222]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:39 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:39 primo kernel: aml_tdm_open Oct 03 13:06:39 primo kernel: Not init audio effects Oct 03 13:06:39 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:39 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:39 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:39 primo kernel: aml_tdm_open Oct 03 13:06:39 primo kernel: Not init audio effects Oct 03 13:06:39 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:39 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:39 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:39 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:39 primo volumio[3533]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 13:06:39 primo volumio[3533]: info: Preparing to save Alsa Options, stopping services first Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioPause Oct 03 13:06:39 primo volumio[3533]: info: CoreStateMachine::pause Oct 03 13:06:39 primo volumio[3533]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Outputs"}} Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:39 primo volumio[3533]: info: Setting mixer Audio hdmi-out mute for card Analog Outputs Oct 03 13:06:39 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:06:39 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:39 primo sudo[32264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:39 primo sudo[32264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:39 primo volumio[3533]: info: Applying Volume Override Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:06:39 primo volumio[3533]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:06:39 primo volumio[3533]: info: Enabling external Volume Control Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:39 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:39 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:39 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:39 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:39 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:39.794+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:39.794+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:39.795+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:39 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:39.796+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:39 primo volumio[3533]: info: Preparing to generate the ALSA configuration file Oct 03 13:06:39 primo sudo[32264]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:39 primo sudo[32270]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:39 primo sudo[32270]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:39 primo volumio[3533]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:06:39 primo volumio[3533]: info: Reading ALSA contributions from plugins. Oct 03 13:06:39 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:39 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:39 primo volumio[3533]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 03 13:06:39 primo sudo[32276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:39 primo sudo[32276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo sudo[32270]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:40 primo sudo[32276]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo sudo[32283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:40 primo sudo[32283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo volumio[3533]: info: Asound.conf file unchanged, so no further update is needed Oct 03 13:06:40 primo volumio[3533]: info: Output device has changed, restarting MPD Oct 03 13:06:40 primo volumio[3533]: info: Output device has changed, restarting Shairport Sync Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:40 primo sudo[32287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:06:40 primo sudo[32287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo sudo[32289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:06:40 primo sudo[32289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo sudo[32287]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo volumio[3533]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:06:40 primo volumio[3533]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:40 primo kernel: aml_tdm_open Oct 03 13:06:40 primo kernel: Not init audio effects Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo sudo[32297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:40 primo sudo[32297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:06:40 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:06:40 primo sudo[32300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:40 primo sudo[32300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:06:40 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:06:40 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:06:40 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:40 primo sudo[32308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:40 primo sudo[32308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo volumio[3533]: info: MPD Permissions set Oct 03 13:06:40 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:06:40 primo qobuz-connect[32127]: 20261003 13:06:40.393 [32127.32127] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:06:40 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:40.429+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:40.430+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:40 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:06:40 primo volumio[3533]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:06:40 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:40 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:40 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:40 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:40.450+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:40 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:40.450+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:40 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:40 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:06:40 primo sudo[32308]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:40 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:40 primo sudo[32283]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo sudo[32170]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo sudo[32149]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo sudo[32322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:40 primo sudo[32322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:40 primo volumio[3533]: info: Starting Shairport Sync Oct 03 13:06:40 primo volumio[3533]: info: Executing endpoint qc_getconfig Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:06:40 primo sudo[32327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:40 primo sudo[32327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo sudo[32329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:06:40 primo sudo[32329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo sudo[32297]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.676 [32321.32321] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:06:40 primo volumio[3533]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:06:40 primo volumio[3533]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.682 [32321.32321] INFO VolumeManager: [0xac90bf58]: Setting new playback volume: 75 Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.682 [32321.32321] INFO VolumeManager: [0xac90bf58]: Setting new mute state: 0 Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.682 [32321.32321] INFO AudioStreamManager: [0xac90bcb0]: Setting new audio download buffer size: 1048576 Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.682 [32321.32321] INFO QobuzConnect: [0xac90c820]: Client initialized! Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.682 [32321.32321] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:06:40 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:40 primo sudo[32300]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.721 [32321.32321] INFO LocalConfigManager: [0xac90b9d8]: Starting Local Configuration server Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.721 [32321.32321] INFO SampleApp: Starting Local configuration server Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.722 [32321.32321] INFO SampleApp: Connected to UNIX socket client 0xac8f68f8 Oct 03 13:06:40 primo sudo[32327]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo qobuz-connect[32321]: 20261003 13:06:40.771 [32321.32321] INFO SampleApp: Stopping Local configuration server Oct 03 13:06:40 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:06:40 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:06:40 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:06:40 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:40 primo sudo[32346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:40 primo sudo[32346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:40 primo sudo[32329]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:40 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:40 primo volumio[3533]: info: Shairport-Sync Started Oct 03 13:06:40 primo volumio[3533]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:40 primo volumio[3533]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:40 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:40 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:40 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:40 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:40 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:40 primo sudo[32346]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:40 primo sudo[32354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:06:40 primo sudo[32354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:40 primo kernel: aml_tdm_open Oct 03 13:06:40 primo kernel: Not init audio effects Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo kernel: aml_tdm_open Oct 03 13:06:40 primo kernel: Not init audio effects Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:40 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:40 primo sudo[32314]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:06:40 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:41 primo sudo[32314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:06:41 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:41 primo sudo[32314]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo sudo[32382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:41 primo sudo[32382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:41 primo volumio[3533]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:41 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:41 primo sudo[32354]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo sudo[32382]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo sudo[32392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:41 primo sudo[32392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 03 13:06:41 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:41 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:41 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:41 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:41 primo sudo[32392]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:41 primo kernel: aml_tdm_open Oct 03 13:06:41 primo kernel: Not init audio effects Oct 03 13:06:41 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:41 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:41 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:41 primo kernel: aml_tdm_open Oct 03 13:06:41 primo kernel: Not init audio effects Oct 03 13:06:41 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:41 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:41 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:41 primo volumio[3533]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 13:06:41 primo volumio[3533]: info: Preparing to save Alsa Options, stopping services first Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioPause Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::pause Oct 03 13:06:41 primo volumio[3533]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Outputs"}} Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:41 primo volumio[3533]: info: Setting mixer Audio hdmi-out mute for card Analog Outputs Oct 03 13:06:41 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:06:41 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:41 primo sudo[32413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:41 primo sudo[32413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: Applying Volume Override Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:06:41 primo volumio[3533]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:06:41 primo volumio[3533]: info: Enabling external Volume Control Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.569+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.569+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.573+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.573+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:41 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:06:41 primo volumio[3533]: info: Preparing to generate the ALSA configuration file Oct 03 13:06:41 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:06:41 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:41 primo sudo[32417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:41 primo sudo[32417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::getcurrentVolume Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:41 primo sudo[32413]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:41 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.702+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:41 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:41.702+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:41 primo volumio[3533]: info: RAAT: Requesting Headphone Status Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:06:41 primo volumio[3533]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:06:41 primo volumio[3533]: info: Reading ALSA contributions from plugins. Oct 03 13:06:41 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:41 primo sudo[32423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:41 primo sudo[32423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:41 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:41 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:41 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:41 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:41 primo volumio[3533]: info: RAAT: Requesting Headphone Status Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:06:41 primo volumio[3533]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 03 13:06:41 primo volumio[3533]: info: MCU Signalled Headphone Mode Disabled Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 13:06:41 primo volumio[3533]: info: Reporting Headphone State: false Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:41 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:41 primo volumio[3533]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 03 13:06:41 primo volumio[3533]: info: Preparing to save Alsa Options, stopping services first Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioPause Oct 03 13:06:41 primo volumio[3533]: info: CoreStateMachine::pause Oct 03 13:06:41 primo volumio[3533]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Outputs"}} Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:41 primo sudo[32423]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo sudo[32417]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:41 primo sudo[32437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:41 primo sudo[32437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:41 primo volumio[3533]: info: Setting mixer Audio hdmi-out mute for card Analog Outputs Oct 03 13:06:41 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:41 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:41 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:41 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:06:41 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:41 primo sudo[32446]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:41 primo sudo[32446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: Applying Volume Override Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:06:42 primo volumio[3533]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:06:42 primo volumio[3533]: info: Enabling external Volume Control Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:42 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:42 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:42 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:42 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:42 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:42.037+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:42 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:42.037+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:42 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:42.041+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:42 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:42.042+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:42 primo sudo[32446]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:42 primo sudo[32452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:42 primo volumio[3533]: info: RAAT: Requesting Headphone Status Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:06:42 primo sudo[32458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:42 primo sudo[32458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:42 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:42 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:42 primo volumio[3533]: info: Executing endpoint tc_getconfig Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 13:06:42 primo sudo[32452]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32458]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:42 primo sudo[32486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: MCU Signalled Headphone Mode Disabled Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 13:06:42 primo volumio[3533]: info: Reporting Headphone State: false Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:42 primo volumio[3533]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Oct 03 13:06:42 primo volumio[3533]: info: MCU Signalled Headphone Mode Disabled Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 13:06:42 primo volumio[3533]: info: Reporting Headphone State: false Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:42 primo volumio[3533]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:42 primo volumio[3533]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:42 primo volumio[3533]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:42 primo volumio[3533]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:42 primo sudo[32503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:06:42 primo sudo[32503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo sudo[32505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:06:42 primo sudo[32505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:42 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:42 primo sudo[32503]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32505]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo qobuz-connect[32321]: 20261003 13:06:42.735 [32321.32321] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:06:42 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:06:42 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:42 primo volumio[3533]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:42 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:42 primo volumio[3533]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:06:42 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:42 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:42 primo sudo[32519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:42 primo sudo[32437]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32322]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32486]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo sudo[32519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:42 primo sudo[32519]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:42 primo volumio[3533]: info: Asound.conf file unchanged, so no further update is needed Oct 03 13:06:42 primo volumio[3533]: info: Output device has changed, restarting MPD Oct 03 13:06:42 primo sudo[32524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:42 primo sudo[32524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo sudo[32526]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:42 primo sudo[32526]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:42 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:42 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:42 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:43 primo volumio[3533]: info: Output device has changed, restarting Shairport Sync Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:43 primo sudo[32530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:06:43 primo sudo[32530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo sudo[32530]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo kernel: aml_tdm_open Oct 03 13:06:43 primo kernel: Not init audio effects Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: aml_tdm_open Oct 03 13:06:43 primo kernel: Not init audio effects Oct 03 13:06:43 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:43 primo sudo[32532]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:06:43 primo sudo[32532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo sudo[32524]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo volumio[3533]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:06:43 primo volumio[3533]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:43 primo sudo[32526]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo volumio[3533]: info: Preparing to generate the ALSA configuration file Oct 03 13:06:43 primo kernel: aml_tdm_open Oct 03 13:06:43 primo kernel: Not init audio effects Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo sudo[32553]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:43 primo sudo[32553]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo sudo[32555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:43 primo sudo[32555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo sudo[32557]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:43 primo sudo[32557]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:06:43 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:06:43 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:43 primo volumio[3533]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:06:43 primo volumio[3533]: info: Reading ALSA contributions from plugins. Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo sudo[32565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:43 primo sudo[32565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo volumio[3533]: info: Executing endpoint qc_getconfig Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:06:43 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:06:43 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:06:43 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:06:43 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.343 [32520.32520] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.349 [32520.32520] INFO VolumeManager: [0xab5ccf58]: Setting new playback volume: 75 Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.349 [32520.32520] INFO VolumeManager: [0xab5ccf58]: Setting new mute state: 0 Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.349 [32520.32520] INFO AudioStreamManager: [0xab5cccb0]: Setting new audio download buffer size: 1048576 Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.349 [32520.32520] INFO QobuzConnect: [0xab5cd820]: Client initialized! Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.349 [32520.32520] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:06:43 primo volumio[3533]: info: MPD Permissions set Oct 03 13:06:43 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:43 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:43 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:43 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.371 [32520.32520] INFO LocalConfigManager: [0xab5cc9d8]: Starting Local Configuration server Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.371 [32520.32520] INFO SampleApp: Starting Local configuration server Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.372 [32520.32520] INFO SampleApp: Connected to UNIX socket client 0xab5b78f8 Oct 03 13:06:43 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.382+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.383+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:43 primo volumio[3533]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:06:43 primo volumio[3533]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:06:43 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:06:43 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.415+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.415+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:43 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:06:43 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:43 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:43 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.493 [32520.32520] INFO SampleApp: Playback volume changed: 75 Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:43 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:43 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:43 primo sudo[32555]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo sudo[32565]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo sudo[32557]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo sudo[32589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo sudo[32589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo volumio[3533]: info: Executing endpoint tc_getconfig Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 13:06:43 primo volumio[3533]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:43 primo volumio[3533]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:43 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:43 primo sudo[32553]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo volumio[3533]: info: RAAT: Requesting Headphone Status Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:06:43 primo kernel: aml_tdm_open Oct 03 13:06:43 primo kernel: Not init audio effects Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: aml_tdm_open Oct 03 13:06:43 primo kernel: Not init audio effects Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:43 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:43 primo sudo[32596]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:06:43 primo sudo[32596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:43 primo qobuz-connect[32520]: 20261003 13:06:43.812 [32520.32520] INFO SampleApp: Stopping Local configuration server Oct 03 13:06:43 primo volumio[3533]: info: Not Reporting Auto name since its the default one Oct 03 13:06:43 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:06:43 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:43 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:43 primo volumio[3533]: info: Raat Daemon started successfully Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 03 13:06:43 primo volumio[3533]: info: CoreStateMachine::getcurrentVolume Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioRetrievevolume Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Oct 03 13:06:43 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:43 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:43 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.888+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:43 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:43.889+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:43 primo volumio[3533]: info: Starting Shairport Sync Oct 03 13:06:43 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:06:43 primo volumio[3533]: info: MCU Signalled Headphone Mode Disabled Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 13:06:43 primo volumio[3533]: info: Reporting Headphone State: false Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:43 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:43 primo volumio[3533]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:06:43 primo sudo[32596]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:43 primo sudo[32616]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:06:43 primo sudo[32616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo volumio[3533]: info: RAAT: Requesting Headphone Status Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:06:44 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:06:44 primo sudo[32574]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:06:44 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:06:44 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:44 primo sudo[32574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:06:44 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:44 primo volumio[3533]: info: Executing endpoint restartRAATSocket Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:06:44 primo sudo[32574]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:06:44 primo volumio[3533]: info: Asound.conf file unchanged, so no further update is needed Oct 03 13:06:44 primo volumio[3533]: info: Output device has changed, restarting MPD Oct 03 13:06:44 primo sudo[32616]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo volumio[3533]: info: Output device has changed, restarting Shairport Sync Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:44 primo sudo[32629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:06:44 primo sudo[32629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo sudo[32631]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:06:44 primo sudo[32631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo sudo[32629]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo volumio[3533]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:06:44 primo volumio[3533]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:44 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:44 primo volumio[3533]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: Relinquishing Volumio State Oct 03 13:06:44 primo kernel: aml_tdm_open Oct 03 13:06:44 primo kernel: Not init audio effects Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:44 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:44 primo sudo[32657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:44 primo sudo[32657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:06:44 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:06:44 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:06:44 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:06:44 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:06:44 primo sudo[32659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:06:44 primo sudo[32659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:06:44 primo volumio[3533]: info: MCU Signalled Headphone Mode Disabled Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 03 13:06:44 primo volumio[3533]: info: Reporting Headphone State: false Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: Updating RAAT Signal Path Oct 03 13:06:44 primo volumio[3533]: info: CoreStateMachine::pushState Oct 03 13:06:44 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::volumioPushState Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::volumioGetState Oct 03 13:06:44 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:44 primo volumio[3533]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:06:44 primo volumio[3533]: info: MRS: Pushing multiroomSync output Oct 03 13:06:44 primo sudo[32667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:06:44 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:06:44 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:44.477+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" state=STATUS_STOPPED positionMs=0 volume=99 Oct 03 13:06:44 primo volumio5-onboarding[3791]: time=2026-10-03T13:06:44.478+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.26:32828 @ 0x200a6f0" id= title= Oct 03 13:06:44 primo sudo[32667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo volumio[3533]: info: CorePlayQueue::getTrack 0 Oct 03 13:06:44 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:06:44 primo sudo[32671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:06:44 primo sudo[32671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo volumio[3533]: info: MPD Permissions set Oct 03 13:06:44 primo volumio[3533]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:44 primo volumio[3533]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:06:44 primo volumio[3533]: info: Shairport-Sync Started Oct 03 13:06:44 primo volumio[3533]: info: TidalConnect service started! Oct 03 13:06:44 primo sudo[32675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:06:44 primo sudo[32675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo sudo[32667]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:06:44 primo sudo[32671]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:06:44 primo sudo[32659]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo sudo[32657]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo sudo[32679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:06:44 primo sudo[32679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo sudo[32675]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo sudo[32680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:06:44 primo sudo[32680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:06:44 primo volumio[3533]: info: Executing endpoint tc_getconfig Oct 03 13:06:44 primo volumio[3533]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 13:06:44 primo vtcs[32618]: STARTING TidalConnect services, version: 1.6.1 Oct 03 13:06:44 primo vtcs[32618]: STARTED TidalConnect services. Oct 03 13:06:44 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:06:44 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:06:44 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:06:44 primo volumio[3533]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 03 13:06:44 primo volumio[3533]: info: TidalConnect service stoped! Oct 03 13:06:44 primo volumio[3533]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:06:44 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:06:44 primo sudo[32680]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:44 primo volumio[3533]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 03 13:06:44 primo volumio[3533]: Job for vtcs.service canceled. Oct 03 13:06:44 primo volumio[3533]: at ChildProcess.exithandler (node:child_process:421:12) Oct 03 13:06:44 primo volumio[3533]: at ChildProcess.emit (node:events:514:28) Oct 03 13:06:44 primo volumio[3533]: at maybeClose (node:internal/child_process:1105:16) Oct 03 13:06:44 primo volumio[3533]: at Socket. (node:internal/child_process:457:11) Oct 03 13:06:44 primo volumio[3533]: at Socket.emit (node:events:514:28) Oct 03 13:06:44 primo volumio[3533]: at Pipe. (node:net:337:12) { Oct 03 13:06:44 primo volumio[3533]: code: 1, Oct 03 13:06:44 primo volumio[3533]: killed: false, Oct 03 13:06:44 primo volumio[3533]: signal: null, Oct 03 13:06:44 primo volumio[3533]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 03 13:06:44 primo volumio[3533]: stdout: '', Oct 03 13:06:44 primo volumio[3533]: stderr: 'Job for vtcs.service canceled.\n' Oct 03 13:06:44 primo volumio[3533]: } Oct 03 13:06:44 primo volumio[3533]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:06:44 primo kernel: aml_tdm_open Oct 03 13:06:44 primo kernel: Not init audio effects Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:44 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:44 primo kernel: aml_tdm_open Oct 03 13:06:44 primo kernel: Not init audio effects Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 03 13:06:44 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 03 13:06:44 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 03 13:06:45 primo sudo[32672]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:06:45 primo sudo[32672]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:06:45 primo sudo[32672]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:45 primo qobuz-connect[32520]: 20261003 13:06:45.391 [32520.32520] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:06:45 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:06:45 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:45 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:06:45 primo sudo[32589]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:45 primo sudo[32679]: pam_unix(sudo:session): session closed for user root Oct 03 13:06:45 primo sudo[32716]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 13:05' Oct 03 13:06:45 primo sudo[32716]: 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"