Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:27:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioVolatilePlay Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::volatilePlay Oct 03 13:27:35 primo volumio[3437]: ------------------------------------ BT MESSAGE: [FUNC] play Oct 03 13:27:35 primo volumio[3437]: ------------------------------------ BT MESSAGE: sendPlay -> calling D-Bus Play() Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.682+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.683+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:35 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.762+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.766+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:35 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:35 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:35 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.972+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.972+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.975+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.976+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.978+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.979+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.988+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.988+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.990+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:35 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:35.992+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from 1C:71:25:AD:31:0D Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: setWinningMac: 1C:71:25:AD:31:0D Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: Playback started, enabling output (party mode: last play wins) Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: [AAMP] Modular pipeline enabled - forcing ALSA route: volumioLocalPlayback Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: Party mode: using preferred BT MAC: 1C:71:25:AD:31:0D Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: bluealsa-aplay already running for 1C:71:25:AD:31:0D. Skipping. Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: [metaCache] Loaded metadata for 1C:71:25:AD:31:0D from memory Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: Loaded metadata from cache for 1C:71:25:AD:31:0D Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:36 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:36 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:36 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:36 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:36 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:36 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:36 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:36 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:36.026+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:36 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:36.027+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:36 primo bluealsa[3284]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Oct 03 13:27:36 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:36.028+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:36 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:36.028+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:36 primo bluealsa[3284]: ../src/ba-transport.c:319: New A2DP transport: 15 Oct 03 13:27:36 primo bluealsa[3284]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:672 W:1004 Oct 03 13:27:36 primo bluealsa[3284]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Oct 03 13:27:36 primo bluealsa[3284]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Oct 03 13:27:36 primo bluealsa[3284]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: Debounce: dropped pending playback=false for 1C:71:25:AD:31:0D (true arrived within window) Oct 03 13:27:36 primo bluealsa[3284]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Oct 03 13:27:36 primo bluealsa[3284]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:36 primo bluealsa[3284]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps Oct 03 13:27:36 primo kernel: aml_spdif_open Oct 03 13:27:36 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:27:36 primo kernel: set normal 512 fs /4 fs Oct 03 13:27:36 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:27:36 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:27:36 primo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Oct 03 13:27:36 primo kernel: set normal 512 fs /4 fs Oct 03 13:27:36 primo kernel: set spdifout clk:5644800, mpll:22579200 Oct 03 13:27:36 primo kernel: get spdifout clk:5644797, mpll:22579186 Oct 03 13:27:36 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:27:36 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:27:36 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Oct 03 13:27:36 primo kernel: aml_spdif_fifo_ctrl, bit depth:16, frddr type:1, toddr:type:1 Oct 03 13:27:36 primo volumio[3437]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [12169] D: aplay.c:732: Opening ALSA playback PCM: name=volumioLocalPlayback channels=2 rate=44100 Oct 03 13:27:36 primo volumio[3437]: bluealsa-aplay: [12169] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 Oct 03 13:27:36 primo volumio[3437]: bluealsa-aplay: [12169] W: aplay.c:350: Couldn't open ALSA mixer: Mixer element not found Oct 03 13:27:36 primo volumio[3437]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 03 13:27:36 primo volumio[3437]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Oct 03 13:27:36 primo volumio[3437]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 03 13:27:36 primo volumio[3437]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Oct 03 13:27:36 primo volumio[3437]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Oct 03 13:27:36 primo volumio[3437]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Oct 03 13:27:36 primo volumio[3437]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Oct 03 13:27:36 primo volumio[3437]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 13:27:36 primo volumio[3437]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 03 13:27:36 primo volumio[3437]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 03 13:27:36 primo volumio[3437]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Oct 03 13:27:36 primo volumio[3437]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 03 13:27:36 primo volumio[3437]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Oct 03 13:27:36 primo volumio[3437]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Oct 03 13:27:36 primo volumio[3437]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Oct 03 13:27:36 primo volumio[3437]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Oct 03 13:27:36 primo volumio[3437]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 13:27:36 primo volumio[3437]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 03 13:27:36 primo volumio[3437]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 03 13:27:36 primo volumio[3437]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Oct 03 13:27:36 primo volumio[3437]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 03 13:27:36 primo volumio[3437]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Oct 03 13:27:36 primo volumio[3437]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Oct 03 13:27:36 primo volumio[3437]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Oct 03 13:27:36 primo volumio[3437]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Oct 03 13:27:36 primo volumio[3437]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 13:27:36 primo volumio[3437]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 03 13:27:36 primo volumio[3437]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 03 13:27:36 primo volumio[3437]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Oct 03 13:27:36 primo volumio[3437]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 03 13:27:36 primo volumio[3437]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Oct 03 13:27:36 primo volumio[3437]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Oct 03 13:27:36 primo volumio[3437]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Oct 03 13:27:36 primo volumio[3437]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Oct 03 13:27:36 primo volumio[3437]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 13:27:36 primo volumio[3437]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 03 13:27:36 primo volumio[3437]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Oct 03 13:27:36 primo volumio[3437]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Oct 03 13:27:36 primo volumio[3437]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Oct 03 13:27:36 primo volumio[3437]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Oct 03 13:27:36 primo volumio[3437]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Oct 03 13:27:36 primo volumio[3437]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Oct 03 13:27:36 primo volumio[3437]: at Promise._failFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:449:13) Oct 03 13:27:36 primo volumio[3437]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 13:27:36 primo volumio[3437]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 03 13:27:36 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [31 != 28]: 3 Oct 03 13:27:36 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [39 != 38]: 1 Oct 03 13:27:36 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [44 != 43]: 1 Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [47 != 45]: 2 Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [49 != 48]: 1 Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [52 != 50]: 2 Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [57 != 55]: 2 Oct 03 13:27:37 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [60 != 59]: 1 Oct 03 13:27:38 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [172 != 171]: 1 Oct 03 13:27:38 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [175 != 173]: 2 Oct 03 13:27:38 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [180 != 178]: 2 Oct 03 13:27:39 primo volumio[3437]: ------------------------------------ BT MESSAGE: Fallback: pushing metadata after resume Oct 03 13:27:39 primo volumio[3437]: info: CoreCommandRouter::servicePushState Oct 03 13:27:39 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:27:39 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:27:39 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:27:39 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:27:39 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:27:39 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:27:39 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:39.032+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:39 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:39.033+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2726930" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:27:39 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:27:39 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:27:39 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:39.070+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=11 index=0 tries=11 Oct 03 13:27:39 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [233 != 230]: 3 Oct 03 13:27:39 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [236 != 234]: 2 Oct 03 13:27:39 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [244 != 243]: 1 Oct 03 13:27:39 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [249 != 247]: 2 Oct 03 13:27:41 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [389 != 388]: 1 Oct 03 13:27:41 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [396 != 395]: 1 Oct 03 13:27:42 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:42.380+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=11 index=0 tries=11 Oct 03 13:27:42 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:42.380+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%04 @ 0x2726930" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="peer is gone" Oct 03 13:27:42 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:42.511+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:42 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:42.795+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:43 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:43.051+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:43 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:43.287+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:43 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:43.639+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:43 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:43.937+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:43 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:43.993+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:27:44 primo volumio5-onboarding[4177]: time=2026-10-03T13:27:44.234+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:08 primo bluealsa[3284]: ../src/rtp.c:145: Missing RTP packets [2202 != 2200]: 2 Oct 03 13:28:12 primo volumio[3437]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: Restoring Previous Volume level: 100 false true Oct 03 13:28:12 primo volumio[3437]: info: VolumeController::SetAlsaVolume100 Oct 03 13:28:12 primo volumio[3437]: info: Enable softmixer device for audio device number 0,2 Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioStop Oct 03 13:28:12 primo volumio[3437]: info: CoreStateMachine::stop Oct 03 13:28:12 primo volumio[3437]: info: CoreStateMachine::serviceStop Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::serviceStop Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: [FUNC] stop Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: Killing bluealsa-aplay process Oct 03 13:28:12 primo bluealsa[3284]: ../src/ba-transport-pcm.c:453: Closing PCM: 18 Oct 03 13:28:12 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Oct 03 13:28:12 primo kernel: spdif_a keep clk continuous Oct 03 13:28:12 primo kernel: aml_spdif_close Oct 03 13:28:12 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:12 primo volumio[3437]: info: Enable softmixer device for audio device undefined Oct 03 13:28:12 primo bluealsa[3284]: ../src/ba-transport.c:203: PCM clients check keep-alive: 0 ms Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:28:12 primo volumio[3437]: info: Updating Volume Controller Parameters: Device: 0,2 Name: S/PDIF Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:28:12 primo volumio[3437]: info: Disabling external Volume Control Oct 03 13:28:12 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:12 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:12 primo sudo[12730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:12 primo sudo[12730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo sudo[12730]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:12 primo sudo[12732]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:12 primo sudo[12732]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:12 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo vtcs[4053]: [2026-10-03 13:28:12.544] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 03 13:28:12 primo vtcs[4053]: [2026-10-03 13:28:12.546] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 03 13:28:12 primo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 03 13:28:12 primo volumio[3437]: info: Volume configurations have been set Oct 03 13:28:12 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:28:12 primo kernel: aml_spdif_open Oct 03 13:28:12 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:12 primo kernel: spdif_a keep clk continuous Oct 03 13:28:12 primo kernel: aml_spdif_close Oct 03 13:28:12 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:12 primo sudo[12741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:12 primo sudo[12741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo sudo[12744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:12 primo sudo[12744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:28:12 primo volumio[3437]: info: Updating Volume Controller Parameters: Device: 0,2 Name: S/PDIF Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:28:12 primo volumio[3437]: info: Disabling external Volume Control Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 13:28:12 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:12 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: Bluetooth audio output stopped Oct 03 13:28:12 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:12 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:28:12 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.684+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.685+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.688+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.688+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:12 primo sudo[12741]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:12 primo sudo[12756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:12 primo sudo[12756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo sudo[12759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:12 primo sudo[12759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:12 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:12 primo sudo[12766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:12 primo sudo[12766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo sudo[12744]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:12 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:28:12 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:28:12 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:28:12 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:28:12 primo volumio[3437]: ------------------------------------ BT MESSAGE: bluealsa-aplay exited with code null, signal SIGKILL Oct 03 13:28:12 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:12 primo sudo[12773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:12 primo sudo[12773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:12 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.888+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume= Oct 03 13:28:12 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:12.889+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume= Oct 03 13:28:12 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:12 primo volumio[3437]: info: Software Volume ALSA configuration written Oct 03 13:28:12 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:12 primo sudo[12766]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:12 primo sudo[12773]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:12 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:12 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:12 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:12 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:12 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:12 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:12 primo sudo[12776]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:12 primo sudo[12776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo sudo[12777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:12 primo sudo[12777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:12 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:12 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo sudo[12759]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12756]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 03 13:28:13 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:13 primo qobuz-connect[3926]: 20261003 13:28:13.115 [3926.3926] INFO SampleApp: Stopping Local configuration server Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:13 primo volumio[3437]: info: VolumeController::SetAlsaVolume0 Oct 03 13:28:13 primo volumio[3437]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 0 - forwarding to Bluetooth device Oct 03 13:28:13 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:13 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:13 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:13 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:13.259+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=0 Oct 03 13:28:13 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:13.260+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume=0 Oct 03 13:28:13 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:28:13 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:28:13 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:13 primo sudo[12812]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:13 primo sudo[12812]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:13 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:13 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:13 primo sudo[12817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:13 primo sudo[12817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:13 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:13 primo sudo[12812]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12785]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:13 primo sudo[12785]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:13 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:13 primo sudo[12817]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12785]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12823]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:13 primo sudo[12823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:13 primo sudo[12825]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:13 primo sudo[12825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo sudo[12828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:13 primo sudo[12828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo sudo[12823]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12828]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:13 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:13 primo sudo[12844]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:13 primo sudo[12844]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:13 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:13 primo sudo[12825]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:13 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:13 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:13 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:13 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:13 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:13 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:13 primo kernel: aml_spdif_open Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo kernel: spdif_a keep clk continuous Oct 03 13:28:13 primo kernel: aml_spdif_close Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo kernel: aml_spdif_open Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo kernel: spdif_a keep clk continuous Oct 03 13:28:13 primo kernel: aml_spdif_close Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:13 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:13 primo sudo[12859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:13 primo sudo[12859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:13 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:13 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:13 primo sudo[12859]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12844]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:13 primo sudo[12862]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:13 primo sudo[12862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:13 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo kernel: aml_spdif_open Oct 03 13:28:13 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:13 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo kernel: spdif_a keep clk continuous Oct 03 13:28:13 primo kernel: aml_spdif_close Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo kernel: aml_spdif_open Oct 03 13:28:13 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:13 primo volumio[3437]: ALSA lib ./src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Oct 03 13:28:13 primo volumio[3437]: aplay: main:831: audio open error: Device or resource busy Oct 03 13:28:13 primo sudo[12878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:13 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:13 primo sudo[12878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:13 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:13 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:13 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:13 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:13 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:13 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:13 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:13 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:13 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:13 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:13 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:13 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:13 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 03 13:28:13 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:13 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:13.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:13 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:13.954+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:13 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:13 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:14 primo sudo[12891]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:14 primo sudo[12891]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:14 primo sudo[12878]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo sudo[12892]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:14 primo sudo[12892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12895]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:14 primo sudo[12895]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.145+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.146+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:14 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:14 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo sudo[12892]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:14 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo sudo[12906]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:14 primo sudo[12906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 03 13:28:14 primo volumio[3437]: info: Updating Volume Controller Parameters: Device: 0,2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = 1C:71:25:AD:31:0D Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = 1C:71:25:AD:31:0D Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = 1C:71:25:AD:31:0D Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for 1C:71:25:AD:31:0D: {"Volume":true} Oct 03 13:28:14 primo volumio[3437]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Oct 03 13:28:14 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:14 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Oct 03 13:28:14 primo volumio[3437]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Oct 03 13:28:14 primo sudo[12891]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 03 13:28:14 primo volumio[3437]: info: Disabling external Volume Control Oct 03 13:28:14 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:14 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:14 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:14 primo sudo[12918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:14 primo sudo[12918]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:14 primo sudo[12920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12923]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:14 primo sudo[12923]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:14 primo sudo[12931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:14 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:14 primo sudo[12937]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:14 primo sudo[12937]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.615+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.617+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:14 primo sudo[12944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:14 primo sudo[12944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo qobuz-connect[3926]: 20261003 13:28:14.680 [3926.3926] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:28:14 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:14 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:14 primo sudo[12931]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:14 primo sudo[12937]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo sudo[12944]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo sudo[12956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:14 primo sudo[12956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.853+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:14 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:14.853+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:14 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:14 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:14 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:14 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:14 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:14 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:14 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:14 primo volumio[3437]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:28:14 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:14 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:14 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:14 primo sudo[12955]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:14 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:14 primo sudo[12955]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12954]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:14 primo sudo[12954]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:14 primo sudo[12777]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo sudo[12776]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:14 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo sudo[12920]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:15 primo sudo[12923]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo sudo[12918]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:15 primo sudo[12906]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo kernel: spdif_a keep clk continuous Oct 03 13:28:15 primo kernel: aml_spdif_close Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:15 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:15 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:15 primo sudo[13004]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:15 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:15 primo sudo[13004]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[12955]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:15 primo sudo[12956]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo sudo[12954]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo sudo[13004]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:15 primo sudo[13012]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:15 primo sudo[13013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:15 primo sudo[13012]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[13013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[13013]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:15 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:15 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:15 primo volumio[3437]: info: Executing endpoint qc_getconfig Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:28:15 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:15 primo sudo[13019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:15 primo sudo[13012]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo sudo[13019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[13020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:15 primo sudo[13020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:15 primo kernel: aml_spdif_open Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo kernel: spdif_a keep clk continuous Oct 03 13:28:15 primo kernel: aml_spdif_close Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo kernel: aml_spdif_open Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo sudo[13019]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:15 primo volumio[3437]: info: Executing endpoint restartRAATSocket Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:28:15 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:15 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:15 primo sudo[13039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:15 primo sudo[13039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[13041]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:15 primo sudo[13041]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[13042]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:15 primo sudo[13042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo sudo[12939]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:15 primo sudo[12939]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:15 primo volumio[3437]: info: Executing endpoint qc_getconfig Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:28:15 primo sudo[12939]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.749 [13006.13006] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.758 [13006.13006] INFO VolumeManager: [0xabf5e058]: Setting new playback volume: 75 Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.758 [13006.13006] INFO VolumeManager: [0xabf5e058]: Setting new mute state: 0 Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.758 [13006.13006] INFO AudioStreamManager: [0xabf5ddb0]: Setting new audio download buffer size: 1048576 Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.759 [13006.13006] INFO QobuzConnect: [0xabf5e920]: Client initialized! Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.759 [13006.13006] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:28:15 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:15.777+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:15 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:15.786+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" id= title="Violin Sonata No. 9 in A Major, Op. 47 \"Kreutzer\": III. Finale. Presto" Oct 03 13:28:15 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:15 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:15 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:15 primo volumio[3437]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:28:15 primo volumio[3437]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.807 [13006.13006] INFO LocalConfigManager: [0xabf5dad8]: Starting Local Configuration server Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.808 [13006.13006] INFO SampleApp: Starting Local configuration server Oct 03 13:28:15 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.819 [13006.13006] INFO SampleApp: Connected to UNIX socket client 0xabf488f8 Oct 03 13:28:15 primo kernel: spdif_a keep clk continuous Oct 03 13:28:15 primo kernel: aml_spdif_close Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo volumio[3437]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Oct 03 13:28:15 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:15 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:15 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:15 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Oct 03 13:28:15 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:15 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:15 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:15 primo sudo[13053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:15 primo sudo[13053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:15 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:15 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:15 primo qobuz-connect[13006]: 20261003 13:28:15.898 [13006.13006] INFO SampleApp: Playback volume changed: 75 Oct 03 13:28:15 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:15 primo sudo[13041]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo sudo[13020]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:15 primo kernel: aml_spdif_open Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo kernel: spdif_a keep clk continuous Oct 03 13:28:15 primo kernel: aml_spdif_close Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo kernel: aml_spdif_open Oct 03 13:28:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:15 primo sudo[13061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:15 primo sudo[13061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo sudo[13061]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:16 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:16 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:16 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:16 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:16 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:16 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:16 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:16 primo sudo[13085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:16 primo sudo[13085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:16 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo sudo[13086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:16 primo sudo[13086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo sudo[13085]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:16 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:16 primo sudo[13095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:16 primo sudo[13095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo sudo[13097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:16 primo sudo[13097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:16 primo sudo[13039]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:16 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:16 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:16 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:16 primo sudo[13042]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:16 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:16 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:16 primo sudo[13104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:16 primo sudo[13104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:16 primo sudo[13104]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:16 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:16 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:16 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:16 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:16 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo sudo[13123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:16 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:16 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:16 primo sudo[13123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:16 primo sudo[13097]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:16 primo sudo[13095]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:16 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:16 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:16 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:16 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:16 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:16 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:16 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:16 primo volumio[3437]: info: VolumeController::SetAlsaVolume100 Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 100 - forwarding to Bluetooth device Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] self.currentMAC = 1C:71:25:AD:31:0D Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] transportManager.currentMAC = 1C:71:25:AD:31:0D Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] MAC resolved for push = 1C:71:25:AD:31:0D Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: [DEBUG] Capabilities for 1C:71:25:AD:31:0D: {"Volume":true} Oct 03 13:28:16 primo volumio[3437]: ------------------------------------ BT MESSAGE: pushVolumeToDevice: Device does not support D-Bus volume control (Volume method missing or inactive player) Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setVolume Oct 03 13:28:16 primo volumio[3437]: info: Error : CoreCommandRouter::executeOnPlugin: No method [setVolume] in plugin alsa_controller Oct 03 13:28:16 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:16 primo volumio[3437]: info: CoreStateMachine::pushState Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::volumioPushState Oct 03 13:28:16 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:16 primo volumio[3437]: info: MRS: Pushing multiroomSync output update for this device Oct 03 13:28:16 primo volumio[3437]: info: MRS: Pushing multiroomSync output Oct 03 13:28:16 primo sudo[13140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:16 primo sudo[13140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo sudo[13141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:16 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:16.978+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.178.37:50487,00:00:00:00:00:00%04 @ 0x24a8510" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:16 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:16.981+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x2787020" state=STATUS_PLAYING positionMs=124492 volume=100 Oct 03 13:28:16 primo sudo[13141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:16 primo volumio[3437]: info: Signalling Playback active due to playback status change Oct 03 13:28:17 primo qobuz-connect[13006]: 20261003 13:28:17.004 [13006.13006] INFO SampleApp: Stopping Local configuration server Oct 03 13:28:17 primo volumio[3437]: info: Updating RAAT Signal Path Oct 03 13:28:17 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:17 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:17 primo kernel: spdif_a keep clk continuous Oct 03 13:28:17 primo kernel: aml_spdif_close Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo sudo[13141]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo volumio[3437]: info: Asound.conf file unchanged, so no further update is needed Oct 03 13:28:17 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:17 primo sudo[13140]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo sudo[13158]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:17 primo sudo[13158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo sudo[13148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:17 primo sudo[13148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:17 primo sudo[13162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:17 primo sudo[13162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo sudo[13158]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo sudo[13162]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo sudo[13165]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:17 primo sudo[13165]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo sudo[13168]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:17 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:17 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo sudo[13168]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:17 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:17 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:17 primo kernel: aml_spdif_open Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo kernel: spdif_a keep clk continuous Oct 03 13:28:17 primo kernel: aml_spdif_close Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo sudo[13178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:17 primo sudo[13178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:17 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:17 primo sudo[13148]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo sudo[13179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:17 primo sudo[13179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:17 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:17 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:17 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:17 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:17 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:17 primo volumio[3437]: info: Executing endpoint restartRAATSocket Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo sudo[13187]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:17 primo sudo[13187]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:17 primo sudo[13187]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:17 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:17 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:17 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:17 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:17 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:17 primo sudo[13211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:17 primo sudo[13211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:17 primo sudo[13212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:17 primo sudo[13212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:17 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:17 primo sudo[13217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:17 primo sudo[13168]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo qobuz-connect[13006]: 20261003 13:28:17.823 [13006.13006] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:28:17 primo sudo[13217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:17 primo sudo[13217]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:17 primo kernel: aml_spdif_open Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo kernel: spdif_a keep clk continuous Oct 03 13:28:17 primo kernel: aml_spdif_close Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo kernel: aml_spdif_open Oct 03 13:28:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:17 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:17 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:28:17 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:17 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:17 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:17 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:17 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:17 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:17 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:17 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:17 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:28:17 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:17 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:17 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:17 primo sudo[13231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:17 primo sudo[13231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13231]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo sudo[13232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:18 primo sudo[13232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:18 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:18 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo sudo[13179]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo sudo[13241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:18 primo sudo[13241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:18 primo sudo[13243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13178]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo sudo[13252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:18 primo sudo[13252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:18 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:18 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:18 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:18 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:18 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:18 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:18 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:18 primo volumio[3437]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:28:18 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:18 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:18 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:18 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:18 primo volumio[3437]: info: Executing endpoint tc_getconfig Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 03 13:28:18 primo sudo[13211]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo sudo[13123]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:18 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:18 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:18 primo sudo[13252]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:18 primo sudo[13280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:18 primo sudo[13280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:18 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:18 primo sudo[13241]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo sudo[13287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:18 primo sudo[13287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13243]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:18 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo sudo[13287]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:18 primo sudo[13299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:18 primo sudo[13299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:18 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:18 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:18 primo volumio[3437]: info: Executing endpoint qc_getconfig Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:28:18 primo sudo[13304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:18 primo sudo[13304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:18 primo sudo[13308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:18 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:18 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:18 primo sudo[13212]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:18 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:18 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:18 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:18 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:18 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:18 primo sudo[13317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:18 primo sudo[13317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo sudo[13318]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:18 primo sudo[13318]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:18 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:19 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:19.113+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=2 index=1 tries=11 Oct 03 13:28:19 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:19 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:19 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:19 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:19 primo sudo[13339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:19 primo sudo[13339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo sudo[13341]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:19 primo sudo[13341]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo sudo[13341]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13339]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo kernel: spdif_a keep clk continuous Oct 03 13:28:19 primo kernel: aml_spdif_close Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:19 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:19 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:19 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:19 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:19 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:19 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:19 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:19 primo sudo[13345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:19 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:19 primo sudo[13345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo sudo[13280]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:19 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:19 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:19 primo sudo[13317]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13308]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13299]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13349]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:19 primo sudo[13349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo sudo[13349]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13311]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:19 primo sudo[13352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:19 primo sudo[13352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:19 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo sudo[13311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:19 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:19 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:19 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:19 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:19 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:19 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo sudo[13311]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo kernel: aml_spdif_open Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo kernel: spdif_a keep clk continuous Oct 03 13:28:19 primo kernel: aml_spdif_close Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo sudo[13368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:19 primo sudo[13368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo sudo[13371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:19 primo sudo[13371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:19 primo volumio[3437]: info: Executing endpoint qc_getconfig Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:28:19 primo sudo[13378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:19 primo sudo[13378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:19 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:19 primo sudo[13304]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo sudo[13345]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:19 primo kernel: aml_spdif_open Oct 03 13:28:19 primo sudo[13318]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:19 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo kernel: spdif_a keep clk continuous Oct 03 13:28:19 primo kernel: aml_spdif_close Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo kernel: aml_spdif_open Oct 03 13:28:19 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.832 [13347.13347] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.844 [13347.13347] INFO VolumeManager: [0xab949058]: Setting new playback volume: 75 Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.844 [13347.13347] INFO VolumeManager: [0xab949058]: Setting new mute state: 0 Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.844 [13347.13347] INFO AudioStreamManager: [0xab948db0]: Setting new audio download buffer size: 1048576 Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.844 [13347.13347] INFO QobuzConnect: [0xab949920]: Client initialized! Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.844 [13347.13347] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:28:19 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:28:19 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:19 primo volumio[3437]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:28:19 primo volumio[3437]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:28:19 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:19 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:19 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:19 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:19 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:19 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:19 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.976 [13347.13347] INFO LocalConfigManager: [0xab948ad8]: Starting Local Configuration server Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.976 [13347.13347] INFO SampleApp: Starting Local configuration server Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.976 [13347.13347] INFO SampleApp: Connected to UNIX socket client 0xab9338f8 Oct 03 13:28:19 primo qobuz-connect[13347]: 20261003 13:28:19.983 [13347.13347] INFO SampleApp: Playback volume changed: 75 Oct 03 13:28:19 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:20 primo sudo[13368]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:20 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:20 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:20 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:20 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:20 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:20 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:20 primo sudo[13371]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:20 primo sudo[13378]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:20 primo sudo[13428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:20 primo sudo[13428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:20 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:20 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:20 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:20 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:20 primo sudo[13429]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:20 primo sudo[13430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:20 primo sudo[13429]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo sudo[13430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:20 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:20 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:20 primo sudo[13440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:20 primo sudo[13440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo qobuz-connect[13347]: 20261003 13:28:20.438 [13347.13347] INFO SampleApp: Stopping Local configuration server Oct 03 13:28:20 primo sudo[13442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:20 primo sudo[13442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:20 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:20 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:20 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:20 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:20 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:20 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:20 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:20 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:20 primo sudo[13442]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:20 primo sudo[13448]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:20 primo sudo[13448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo sudo[13448]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:20 primo sudo[13428]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo sudo[13450]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:20 primo sudo[13450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:20 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:20 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:20 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:20 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 03 13:28:20 primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 03 13:28:20 primo sudo[13440]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo sudo[13463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:20 primo sudo[13463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:20 primo sudo[13465]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:20 primo sudo[13465]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo sudo[13468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:20 primo sudo[13468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo sudo[13430]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:20 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:20 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:20 primo volumio[3437]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 03 13:28:20 primo volumio[3437]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 03 13:28:20 primo volumio[3437]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 03 13:28:20 primo volumio[3437]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 03 13:28:20 primo volumio[3437]: followed by "systemctl start shairport-sync.service" again. Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo sudo[13474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:20 primo sudo[13474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:20 primo kernel: spdif_a keep clk continuous Oct 03 13:28:20 primo kernel: aml_spdif_close Oct 03 13:28:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:20 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:20 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:28:20 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:20 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:20 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:20 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:20 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:21 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:21 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:21 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:21 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:21 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:21 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:21 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:28:21 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:21 primo sudo[13483]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:21 primo sudo[13483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:21 primo sudo[13486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13486]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:21 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:21 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:21 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:21 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:21 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:21 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:21 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:21 primo sudo[13491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:21 primo sudo[13491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:21 primo sudo[13493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13491]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:21 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:21 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo sudo[13463]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo kernel: aml_spdif_open Oct 03 13:28:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:21 primo kernel: spdif_a keep clk continuous Oct 03 13:28:21 primo kernel: aml_spdif_close Oct 03 13:28:21 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:21 primo sudo[13504]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:21 primo sudo[13468]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo sudo[13504]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13506]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:21 primo sudo[13506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13474]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:21 primo sudo[13514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:21 primo sudo[13514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:21 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:21 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:21 primo sudo[13519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:21 primo sudo[13519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:21 primo sudo[13514]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:21 primo sudo[13534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:21 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:21 primo sudo[13534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13465]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo kernel: aml_spdif_open Oct 03 13:28:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:21 primo kernel: spdif_a keep clk continuous Oct 03 13:28:21 primo kernel: aml_spdif_close Oct 03 13:28:21 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:21 primo kernel: aml_spdif_open Oct 03 13:28:21 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:21 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:21 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:21 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:21 primo sudo[13545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:21 primo sudo[13545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:21 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:21 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:21 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:21 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:21 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:21 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:21 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:21 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:21 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:21 primo sudo[13552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:21 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:21 primo sudo[13552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 03 13:28:21 primo sudo[13556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:21 primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 03 13:28:21 primo sudo[13556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:21 primo sudo[13558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:21 primo sudo[13558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13483]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo sudo[13558]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:21 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:21 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:21 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:21 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:21 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:21 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:21 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:21 primo sudo[13564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:21 primo sudo[13564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13504]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo sudo[13567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:21 primo sudo[13567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13564]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:21 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:21 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:21 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:21 primo sudo[13577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:21 primo sudo[13577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:21 primo sudo[13545]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:21 primo qobuz-connect[13347]: 20261003 13:28:21.988 [13347.13347] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:28:21 primo sudo[13579]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:21 primo sudo[13579]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:22 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:22 primo sudo[13506]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:22 primo sudo[13584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:22 primo sudo[13585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:22 primo volumio[3437]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Oct 03 13:28:22 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:22 primo volumio[3437]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 03 13:28:22 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:22 primo volumio[3437]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 03 13:28:22 primo volumio[3437]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 03 13:28:22 primo volumio[3437]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 03 13:28:22 primo volumio[3437]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 03 13:28:22 primo volumio[3437]: followed by "systemctl start shairport-sync.service" again. Oct 03 13:28:22 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:22 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:22 primo sudo[13555]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:22 primo sudo[13555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:22 primo sudo[13555]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:22 primo sudo[13556]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13585]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo kernel: spdif_a keep clk continuous Oct 03 13:28:22 primo kernel: aml_spdif_close Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo volumio[3437]: error: Error starting TidalConnect Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3 Oct 03 13:28:22 primo volumio[3437]: Job for vtcs.service canceled. Oct 03 13:28:22 primo volumio[3437]: {"cmd":"/usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3","code":1,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/bin/sudo /bin/systemctl start vtcs.service && sleep 3\nJob for vtcs.service canceled.\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)","stderr":"Job for vtcs.service canceled.\n","stdout":""} Oct 03 13:28:22 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:22 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:22 primo sudo[13610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:22 primo sudo[13610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13609]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:22 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:22 primo sudo[13609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13429]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13534]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 03 13:28:22 primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 03 13:28:22 primo sudo[13519]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:22 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:22.419+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=2 index=0 tries=11 Oct 03 13:28:22 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:22.422+02:00 level=ERROR msg="failed to send event" component=server dst="00:00:00:00:00:00%04 @ 0x2787020" event=SERVER_EVENT_TYPE_PLAYER_METADATA_CHANGED error="peer is gone" Oct 03 13:28:22 primo sudo[13619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:22 primo sudo[13619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:22 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:22 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:22 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:22 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:22 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:22 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:22 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:22 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:22 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:22 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:22 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:22 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:22 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:22 primo volumio[3437]: info: Executing endpoint qc_getconfig Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 03 13:28:22 primo sudo[13626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:22 primo sudo[13626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.560 [13613.13613] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.566 [13613.13613] INFO VolumeManager: [0xac9e2058]: Setting new playback volume: 75 Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.566 [13613.13613] INFO VolumeManager: [0xac9e2058]: Setting new mute state: 0 Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.566 [13613.13613] INFO AudioStreamManager: [0xac9e1db0]: Setting new audio download buffer size: 1048576 Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.566 [13613.13613] INFO QobuzConnect: [0xac9e2920]: Client initialized! Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.566 [13613.13613] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:22 primo sudo[13552]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.595 [13613.13613] INFO LocalConfigManager: [0xac9e1ad8]: Starting Local Configuration server Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.595 [13613.13613] INFO SampleApp: Starting Local configuration server Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.595 [13613.13613] INFO SampleApp: Connected to UNIX socket client 0xac9cc8f8 Oct 03 13:28:22 primo volumio[3437]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 03 13:28:22 primo volumio[3437]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 03 13:28:22 primo volumio[3437]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 03 13:28:22 primo volumio[3437]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 03 13:28:22 primo volumio[3437]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 03 13:28:22 primo volumio[3437]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 03 13:28:22 primo volumio[3437]: followed by "systemctl start shairport-sync.service" again. Oct 03 13:28:22 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:22 primo sudo[13577]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:22 primo sudo[13635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13579]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13639]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:22 primo sudo[13639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo qobuz-connect[13613]: 20261003 13:28:22.710 [13613.13613] INFO SampleApp: Playback volume changed: 75 Oct 03 13:28:22 primo sudo[13639]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:22 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:22 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:22 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:22 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:22 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:22 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:22 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:22 primo sudo[13609]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:22 primo sudo[13645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13645]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:22 primo sudo[13647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:22 primo sudo[13649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:22 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:22 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:22 primo kernel: aml_spdif_open Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo kernel: spdif_a keep clk continuous Oct 03 13:28:22 primo kernel: aml_spdif_close Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo sudo[13635]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo sudo[13659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:22 primo sudo[13659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13661]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:22 primo sudo[13661]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo sudo[13663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:22 primo sudo[13663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:22 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:22 primo sudo[13647]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:22 primo sudo[13584]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:22 primo kernel: aml_spdif_open Oct 03 13:28:22 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo kernel: spdif_a keep clk continuous Oct 03 13:28:22 primo kernel: aml_spdif_close Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo kernel: aml_spdif_open Oct 03 13:28:22 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:22 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:22 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:22 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:23 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:23 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:23 primo volumio[3437]: info: Raat Daemon started successfully Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo sudo[13678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:23 primo sudo[13678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:23 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:23 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:23 primo qobuz-connect[13613]: 20261003 13:28:23.079 [13613.13613] INFO SampleApp: Stopping Local configuration server Oct 03 13:28:23 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 03 13:28:23 primo systemd[1]: shairport-sync.service: Start request repeated too quickly. Oct 03 13:28:23 primo systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Oct 03 13:28:23 primo systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:23 primo volumio[3437]: info: Preparing to generate the ALSA configuration file Oct 03 13:28:23 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:23 primo sudo[13626]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo volumio[3437]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Oct 03 13:28:23 primo volumio[3437]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 03 13:28:23 primo volumio[3437]: info: Reading ALSA contributions from plugins. Oct 03 13:28:23 primo sudo[13688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:23 primo sudo[13688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo volumio[3437]: info: TidalConnect service stoped! Oct 03 13:28:23 primo volumio[3437]: info: Asound.conf file written Oct 03 13:28:23 primo sudo[13619]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo sudo[13698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 03 13:28:23 primo sudo[13698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo sudo[13698]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:23 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:23 primo volumio[3437]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 03 13:28:23 primo volumio[3437]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 03 13:28:23 primo volumio[3437]: Hardware is initialized using a generic method Oct 03 13:28:23 primo volumio[3437]: No state is present for card AMLAUGESOUNDMP1 Oct 03 13:28:23 primo volumio[3437]: info: Output device has changed, restarting MPD Oct 03 13:28:23 primo sudo[13678]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo kernel: spdif_a keep clk continuous Oct 03 13:28:23 primo kernel: aml_spdif_close Oct 03 13:28:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:23 primo volumio[3437]: info: Output device has changed, restarting Shairport Sync Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:23 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:23 primo sudo[13705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 03 13:28:23 primo sudo[13705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:23 primo sudo[13705]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo sudo[13703]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:23 primo sudo[13703]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo sudo[13706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 03 13:28:23 primo sudo[13706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo volumio[3437]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 03 13:28:23 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 03 13:28:23 primo volumio[3437]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: QobuzConnect: setDeactiveState invoked Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::volumioGetState Oct 03 13:28:23 primo volumio[3437]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:23 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:23 primo kernel: aml_spdif_open Oct 03 13:28:23 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:23 primo kernel: spdif_a keep clk continuous Oct 03 13:28:23 primo kernel: aml_spdif_close Oct 03 13:28:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:23 primo sudo[13719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:23 primo sudo[13719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo sudo[13733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 03 13:28:23 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 03 13:28:23 primo sudo[13733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:23 primo volumio[3437]: info: RAAT: Requesting Headphone Status Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 03 13:28:23 primo sudo[13688]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo sudo[13741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 03 13:28:23 primo sudo[13741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:23 primo volumio[3437]: info: MPD Permissions set Oct 03 13:28:23 primo volumio[3437]: info: Shairport-Sync Started Oct 03 13:28:23 primo volumio[3437]: info: TidalConnect service started! Oct 03 13:28:23 primo volumio[3437]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Oct 03 13:28:23 primo volumio[3437]: Job for shairport-sync.service failed because start of the service was attempted too often. Oct 03 13:28:23 primo volumio[3437]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Oct 03 13:28:23 primo volumio[3437]: To force a start use "systemctl reset-failed shairport-sync.service" Oct 03 13:28:23 primo volumio[3437]: followed by "systemctl start shairport-sync.service" again. Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo sudo[13659]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 03 13:28:23 primo sudo[13663]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:23 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:23 primo sudo[13686]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:23 primo sudo[13686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:23 primo sudo[13686]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:23 primo volumio[3437]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:23 primo volumio[3437]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 03 13:28:24 primo volumio[3437]: info: Starting Shairport Sync Oct 03 13:28:24 primo sudo[13763]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:24 primo sudo[13763]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo sudo[13766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 03 13:28:24 primo sudo[13766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo sudo[13769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 03 13:28:24 primo sudo[13769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 13:28:24 primo volumio[3437]: info: Not Reporting Auto name since its the default one Oct 03 13:28:24 primo volumio[3437]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 03 13:28:24 primo sudo[13741]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo sudo[13763]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo sudo[13733]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo sudo[13719]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo sudo[13777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 03 13:28:24 primo sudo[13777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo sudo[13779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:24 primo sudo[13779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo volumio[3437]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:28:24 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:24 primo kernel: aml_spdif_open Oct 03 13:28:24 primo volumio[3437]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 03 13:28:24 primo volumio[3437]: Job for vtcs.service canceled. Oct 03 13:28:24 primo volumio[3437]: at ChildProcess.exithandler (node:child_process:421:12) Oct 03 13:28:24 primo volumio[3437]: at ChildProcess.emit (node:events:514:28) Oct 03 13:28:24 primo volumio[3437]: at maybeClose (node:internal/child_process:1105:16) Oct 03 13:28:24 primo volumio[3437]: at Socket. (node:internal/child_process:457:11) Oct 03 13:28:24 primo volumio[3437]: at Socket.emit (node:events:514:28) Oct 03 13:28:24 primo volumio[3437]: at Pipe. (node:net:337:12) { Oct 03 13:28:24 primo volumio[3437]: code: 1, Oct 03 13:28:24 primo volumio[3437]: killed: false, Oct 03 13:28:24 primo volumio[3437]: signal: null, Oct 03 13:28:24 primo volumio[3437]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 03 13:28:24 primo volumio[3437]: stdout: '', Oct 03 13:28:24 primo volumio[3437]: stderr: 'Job for vtcs.service canceled.\n' Oct 03 13:28:24 primo volumio[3437]: } Oct 03 13:28:24 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:24 primo kernel: spdif_a keep clk continuous Oct 03 13:28:24 primo kernel: aml_spdif_close Oct 03 13:28:24 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:24 primo kernel: aml_spdif_open Oct 03 13:28:24 primo volumio[3437]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 13:28:24 primo sudo[13661]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:24 primo sudo[13783]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 03 13:28:24 primo sudo[13783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 03 13:28:24 primo sudo[13766]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 03 13:28:24 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 03 13:28:24 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:24 primo systemd[1]: mpd.service: Deactivated successfully. Oct 03 13:28:24 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 03 13:28:24 primo sudo[13783]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:24 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 03 13:28:24 primo qobuz-connect[13613]: 20261003 13:28:24.602 [13613.13613] INFO SampleApp: shat down connection on UNIX socket Oct 03 13:28:24 primo sudo[13803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 03 13:28:24 primo sudo[13803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:24 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 03 13:28:24 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 03 13:28:24 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 03 13:28:24 primo kernel: spdif_a keep clk continuous Oct 03 13:28:24 primo kernel: aml_spdif_close Oct 03 13:28:24 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:24 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 03 13:28:24 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 03 13:28:24 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 03 13:28:24 primo sudo[13769]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:25 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 03 13:28:25 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:25 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 03 13:28:25 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 03 13:28:25 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 03 13:28:25 primo sudo[13779]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:25 primo kernel: aml_spdif_open Oct 03 13:28:25 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:25 primo kernel: spdif_a keep clk continuous Oct 03 13:28:25 primo kernel: aml_spdif_close Oct 03 13:28:25 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 03 13:28:25 primo kernel: aml_spdif_open Oct 03 13:28:25 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.400+02:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.178.37:50487 Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.403+02:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.178.37:50487 Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.410+02:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.178.37:52101 Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.429+02:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.178.37:52101 @ 0x2786570" latency=12.032361ms timeout=20s Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.429+02:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.178.37:52101 @ 0x2786570" Oct 03 13:28:25 primo sudo[13841]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 13:27' Oct 03 13:28:25 primo sudo[13841]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.486+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.549+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=http://pushupdates.volumio.org duration=61.338585ms Oct 03 13:28:25 primo sudo[13806]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 03 13:28:25 primo sudo[13806]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 03 13:28:25 primo sudo[13806]: pam_unix(sudo:session): session closed for user root Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.753+02:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn addr=4 chunks=2 index=0 tries=11 Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.783+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=296.472858ms Oct 03 13:28:25 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:25.999+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=https://securetoken.googleapis.com duration=511.11624ms Oct 03 13:28:26 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:26.021+02:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=http://cddb.volumio.org duration=533.948147ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 03 13:28:26 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:26.146+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=658.004327ms Oct 03 13:28:26 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 03 13:28:26 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:26.166+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=http://plugins.volumio.org duration=678.430508ms Oct 03 13:28:26 primo volumio5-onboarding[4177]: time=2026-10-03T13:28:26.167+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.178.37:52101 @ 0x2786570" latency=59.308585ms timeout=10s endpoint=https://www.googleapis.com duration=679.517099ms 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"