Oct 17 11:00:00 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:04 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:08 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:12 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:16 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:20 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:24 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:28 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:32 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: Retrieving Cloud Streaming UI Oct 17 11:00:43 primo volumio[3564]: info: Getting Tidal Cloud Configuration Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: Getting Qobuz Cloud Configuration Oct 17 11:00:43 primo volumio[3564]: info: Asking plugin for UI Config Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: Getting Spotify Cloud Configuration Oct 17 11:00:43 primo volumio[3564]: info: Asking plugin for UI Config Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: Saving Spotify Acccount Oct 17 11:00:43 primo volumio[3564]: info: Got it Oct 17 11:00:43 primo volumio[3564]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 17 11:00:43 primo volumio[3564]: info: Got Tidal Cloud Configuration Oct 17 11:00:43 primo volumio[3564]: info: Got it Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::volumioGetBrowseSources Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 17 11:00:47 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:51 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:55 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:00:59 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:03 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:07 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:11 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:15 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:19 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:26 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 17 11:01:30 primo volumio[3564]: info: Preparing to save Alsa Options, stopping services first Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: CorePlayQueue::getTrack 4 Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioPause Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::pause Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::servicePause Oct 17 11:01:30 primo volumio[3564]: info: CorePlayQueue::getTrack 4 Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::servicePause Oct 17 11:01:30 primo volumio[3564]: info: [1792227690145] ControllerQobuz::pause Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 17 11:01:30 primo volumio[3564]: info: ControllerMpd::pause Oct 17 11:01:30 primo volumio[3564]: verbose: ControllerMpd::sendMpdCommand pause Oct 17 11:01:30 primo volumio[3564]: info: Saving Audio Output to: {"output_device":{"value":"0,2","label":"S/PDIF"}} Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:30 primo volumio[3564]: info: Found match in Cards Database for ignoring default Mixer Oct 17 11:01:30 primo volumio[3564]: info: Setting mixer Audio hdmi-out mute for card S/PDIF Oct 17 11:01:30 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 17 11:01:30 primo vtcs[5714]: [2026-10-17 11:01:30.238] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 17 11:01:30 primo vtcs[5714]: [2026-10-17 11:01:30.243] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 17 11:01:30 primo volumio[3564]: info: Ignoring Mixers Options Oct 17 11:01:30 primo volumio[3564]: info: Device 0 does not have any Mixer Control Available Oct 17 11:01:30 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 17 11:01:30 primo sudo[7029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:30 primo sudo[7029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:30 primo sudo[7030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 17 11:01:30 primo volumio[3564]: 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 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 17 11:01:30 primo volumio[3564]: info: Disabling external Volume Control Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:01:30 primo kernel: asoc-aml-card auge_sound: tdm playback stop Oct 17 11:01:30 primo kernel: spdif_a is set to disable Oct 17 11:01:30 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Oct 17 11:01:30 primo kernel: aml_dai_tdm_hw_free(), disable mclk for TDM-B Oct 17 11:01:30 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 17 11:01:30 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:30 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:30 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:30.334Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PLAYING positionMs=107684 volume=100 Oct 17 11:01:30 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:30.335Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:30 primo volumio[3564]: info: Preparing to generate the ALSA configuration file Oct 17 11:01:30 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 17 11:01:30 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 17 11:01:30 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 17 11:01:30 primo sudo[7037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:30 primo sudo[7037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7029]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo sudo[7030]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo sudo[7039]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:30 primo sudo[7039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:30 primo sudo[7048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo volumio[3564]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 11:01:30 primo volumio[3564]: info: Reading ALSA contributions from plugins. Oct 17 11:01:30 primo volumio[3564]: info: Oct 17 11:01:30 primo volumio[3564]: ---------------------------- MPD announces state update: player Oct 17 11:01:30 primo volumio[3564]: info: sendMpdCommand pause took 341 milliseconds Oct 17 11:01:30 primo volumio[3564]: info: ControllerMpd::getState Oct 17 11:01:30 primo volumio[3564]: verbose: ControllerMpd::sendMpdCommand status Oct 17 11:01:30 primo volumio[3564]: info: Signalling Playback active due to playback status change Oct 17 11:01:30 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:30 primo sudo[7037]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo volumio[3564]: info: sendMpdCommand status took 34 milliseconds Oct 17 11:01:30 primo volumio[3564]: verbose: ControllerMpd::parseState Oct 17 11:01:30 primo volumio[3564]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 17 11:01:30 primo volumio[3564]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 17 11:01:30 primo volumio[3564]: verbose: ControllerMpd::parseTrackInfo Oct 17 11:01:30 primo volumio[3564]: info: ControllerMpd::pushState Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::servicePushState Oct 17 11:01:30 primo volumio[3564]: info: CorePlayQueue::getTrack 4 Oct 17 11:01:30 primo volumio[3564]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":108259,"duration":239,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2895 Kbps","isStreaming":false,"title":"file?uid=2979142&eid=47683835&fmt=7&profile=raw&app_id=539451548&cid=2777659&etsp=1792231178&hmac=FvhsliBEKjOkFUQiGfDNd5ofo68","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=2979142&eid=47683835&fmt=7&profile=raw&app_id=539451548&cid=2777659&etsp=1792231178&hmac=FvhsliBEKjOkFUQiGfDNd5ofo68","trackType":"qobuz"} Oct 17 11:01:30 primo volumio[3564]: verbose: CURRENT POSITION 4 Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::syncState stateService pause Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::syncState currentStatus pause Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:30 primo sudo[7054]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:30 primo sudo[7054]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:30 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:30 primo volumio[3564]: info: CoreStateMachine::stPlaybackTimer Oct 17 11:01:30 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:30.541Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:30 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:30.541Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:30 primo volumio[3564]: info: ------------------------------ 62ms Oct 17 11:01:30 primo sudo[7039]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo sudo[7048]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo sudo[7054]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 11:01:30 primo sudo[7058]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:30 primo sudo[7058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:30 primo sudo[7061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:30 primo sudo[7061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 17 11:01:30 primo qobuz-connect[5696]: 20261017 11:01:30.651 [5696.5696] INFO SampleApp: Stopping Local configuration server Oct 17 11:01:30 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 17 11:01:30 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: Asound.conf file written Oct 17 11:01:30 primo sudo[7067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 11:01:30 primo sudo[7067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7067]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:30 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:30 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:30 primo volumio[3564]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 17 11:01:30 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:30 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:30 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:30 primo volumio[3564]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:30fd" "" "" Oct 17 11:01:30 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:30 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:30 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:30 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:30 primo sudo[7072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:30 primo sudo[7072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:30 primo sudo[7074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:30 primo sudo[7072]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:30 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:30 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:30 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:30 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:30 primo kernel: aml_spdif_open Oct 17 11:01:30 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:30 primo kernel: spdif_a keep clk continuous Oct 17 11:01:30 primo kernel: aml_spdif_close Oct 17 11:01:30 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:30 primo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 11:01:31 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:31 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:31 primo sudo[7084]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:31 primo sudo[7084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:31 primo sudo[7086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:31 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo sudo[7089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:31 primo sudo[7089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7086]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:31 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:31 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:31 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:31 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:31 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:31 primo kernel: aml_spdif_open Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo kernel: spdif_a keep clk continuous Oct 17 11:01:31 primo kernel: aml_spdif_close Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo sudo[7098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:31 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:31 primo sudo[7098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7103]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:31 primo sudo[7103]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:31 primo sudo[7111]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:31 primo sudo[7111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:31 primo sudo[7121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:31 primo sudo[7125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:31 primo sudo[7125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:31 primo sudo[7121]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:31 primo sudo[7136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:31 primo sudo[7136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:31 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:31 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:31 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:31 primo volumio[3564]: info: MCU Signalled Playback Inactive Oct 17 11:01:31 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.353Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.353Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:31 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:31 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:31 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:31 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.389Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.390Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.390Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.391Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.392Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:31 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:31.392Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:31 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:31 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:31 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:31 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo sudo[7111]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo sudo[7084]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:31 primo sudo[7098]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:31 primo sudo[7103]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo sudo[7125]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:31 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:31 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:31 primo sudo[7157]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:31 primo sudo[7157]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:31 primo sudo[7159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:31 primo sudo[7159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:31 primo ntpd[3174]: PROTO: 89.46.74.148 unlink local addr 192.168.0.110 -> Oct 17 11:01:31 primo sudo[7167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:31 primo sudo[7167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7159]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:31 primo sudo[7173]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:31 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:31 primo sudo[7173]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo sudo[7167]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:31 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:31 primo sudo[7179]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:31 primo sudo[7179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:31 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:31 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:31 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:31 primo sudo[7181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:31 primo sudo[7181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:31 primo sudo[7185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:31 primo sudo[7185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo kernel: aml_spdif_open Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo kernel: spdif_a keep clk continuous Oct 17 11:01:31 primo kernel: aml_spdif_close Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo kernel: aml_spdif_open Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo kernel: spdif_a keep clk continuous Oct 17 11:01:31 primo kernel: aml_spdif_close Oct 17 11:01:31 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:31 primo sudo[7173]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:31 primo sudo[7201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:31 primo sudo[7201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:31 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:31 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:31 primo sudo[7207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:31 primo sudo[7207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:31 primo volumio[3564]: 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 17 11:01:31 primo sudo[7201]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo sudo[7207]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:32 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 11:01:32 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 11:01:32 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:32 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:32 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:32 primo sudo[7211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:32 primo sudo[7211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:32 primo sudo[7210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:32 primo sudo[7210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:32 primo qobuz-connect[5696]: 20261017 11:01:32.100 [5696.5696] INFO SampleApp: shat down connection on UNIX socket Oct 17 11:01:32 primo volumio[3564]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 17 11:01:32 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:32 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:32 primo sudo[7166]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 11:01:32 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:32 primo sudo[7166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 11:01:32 primo sudo[7179]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:32 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:32 primo sudo[7185]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo kernel: aml_spdif_open Oct 17 11:01:32 primo sudo[7181]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo kernel: spdif_a keep clk continuous Oct 17 11:01:32 primo kernel: aml_spdif_close Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo kernel: aml_spdif_open Oct 17 11:01:32 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:32 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo sudo[7166]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 17 11:01:32 primo kernel: spdif_a keep clk continuous Oct 17 11:01:32 primo kernel: aml_spdif_close Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:32 primo volumio[3564]: (node:3564) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 17 11:01:32 primo volumio[3564]: (node:3564) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 17 11:01:32 primo volumio[3564]: (node:3564) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 17 11:01:32 primo volumio[3564]: (node:3564) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 17 11:01:32 primo volumio[3564]: (node:3564) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Oct 17 11:01:32 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:32 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:32 primo volumio[3564]: 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 17 11:01:32 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:32 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:32 primo sudo[7058]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo sudo[7061]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo sudo[7136]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo sudo[7157]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:32 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:32 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:32 primo sudo[7211]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:32 primo sudo[7210]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:32 primo volumio[3564]: info: Executing endpoint qc_getconfig Oct 17 11:01:32 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 11:01:32 primo kernel: aml_spdif_open Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo kernel: spdif_a keep clk continuous Oct 17 11:01:32 primo kernel: aml_spdif_close Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo kernel: aml_spdif_open Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo kernel: spdif_a keep clk continuous Oct 17 11:01:32 primo kernel: aml_spdif_close Oct 17 11:01:32 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.468 [7246.7246] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 11:01:32 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:32 primo volumio[3564]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 17 11:01:32 primo volumio[3564]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.519 [7246.7246] INFO VolumeManager: [0xac8cc138]: Setting new playback volume: 75 Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.519 [7246.7246] INFO VolumeManager: [0xac8cc138]: Setting new mute state: 0 Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.519 [7246.7246] INFO AudioStreamManager: [0xac8cbe90]: Setting new audio download buffer size: 1048576 Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.519 [7246.7246] INFO QobuzConnect: [0xac8cca00]: Client initialized! Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.519 [7246.7246] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 17 11:01:32 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:32 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:32 primo volumio[3564]: 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 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.562 [7246.7246] INFO LocalConfigManager: [0xac8cbbb8]: Starting Local Configuration server Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.562 [7246.7246] INFO SampleApp: Starting Local configuration server Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.563 [7246.7246] INFO SampleApp: Connected to UNIX socket client 0xac8b68f8 Oct 17 11:01:32 primo qobuz-connect[7246]: 20261017 11:01:32.614 [7246.7246] INFO SampleApp: Playback volume changed: 75 Oct 17 11:01:32 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:33 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:33 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:33 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:33 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:33 primo volumio[3564]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:33 primo volumio[3564]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:33 primo volumio[3564]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:33 primo volumio[3564]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:33 primo sudo[7281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 11:01:33 primo sudo[7281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:33 primo sudo[7283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 11:01:33 primo sudo[7283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:33 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 17 11:01:33 primo sudo[7281]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:33 primo sudo[7283]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:34 primo volumio[3564]: info: Executing endpoint tc_getconfig Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 17 11:01:34 primo vtcs[7288]: STARTING TidalConnect services, version: 1.6.1 Oct 17 11:01:34 primo vtcs[7288]: STARTED TidalConnect services. Oct 17 11:01:34 primo volumio[3564]: info: Executing endpoint tc_connect Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 17 11:01:34 primo volumio[3564]: info: Connecting to TidalConnect Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::servicePushState Oct 17 11:01:34 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:34 primo volumio[3564]: info: CorePlayQueue::getTrack 4 Oct 17 11:01:34 primo volumio[3564]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::servicePushState Oct 17 11:01:34 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:34 primo volumio[3564]: info: CorePlayQueue::getTrack 4 Oct 17 11:01:34 primo volumio[3564]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.195Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.195Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.197Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.197Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 17 11:01:34 primo volumio[3564]: info: CoreStateMachine::getcurrentVolume Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 11:01:34 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:34 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.324Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:34 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:34.324Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:34 primo volumio[3564]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:34 primo volumio[3564]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:34 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:34 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:34 primo sudo[7313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 11:01:34 primo sudo[7313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:34 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:34 primo sudo[7313]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:34 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:34 primo volumio[3564]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:34 primo volumio[3564]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:34 primo volumio[3564]: info: MCU Signalled Headphone Mode Disabled Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 17 11:01:34 primo volumio[3564]: info: Reporting Headphone State: false Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo sudo[7325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 11:01:34 primo sudo[7325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:34 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:34 primo sudo[7325]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:34 primo volumio[3564]: info: MCU Signalled Headphone Mode Disabled Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 17 11:01:34 primo volumio[3564]: info: Reporting Headphone State: false Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:34 primo volumio[3564]: info: MCU Signalled Headphone Mode Disabled Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 17 11:01:34 primo volumio[3564]: info: Reporting Headphone State: false Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:34 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:34 primo volumio[3564]: info: MCU Signalled Headphone Mode Disabled Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 17 11:01:34 primo volumio[3564]: info: Reporting Headphone State: false Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:34 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:35 primo mpd[7230]: 2026-10-17T11:01:35 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 17 11:01:35 primo systemd[1]: Started mpd.service - Music Player Daemon. Oct 17 11:01:35 primo sudo[7089]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:35 primo sudo[7074]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:35 primo volumio[3564]: error: MPD error: The expression evaluated to a falsy value: Oct 17 11:01:35 primo volumio[3564]: assert.ok(self.idling) Oct 17 11:01:35 primo volumio[3564]: error: The expression evaluated to a falsy value: Oct 17 11:01:35 primo volumio[3564]: assert.ok(self.idling) Oct 17 11:01:35 primo volumio[3564]: error: updateQueue error: null Oct 17 11:01:36 primo volumio[3564]: info: TidalConnect service started! Oct 17 11:01:36 primo volumio[3564]: info: TidalConnect service started! Oct 17 11:01:37 primo volumio[3564]: info: TidalConnect service started! Oct 17 11:01:37 primo volumio[3564]: info: TidalConnect service started! Oct 17 11:01:39 primo volumio[3564]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 17 11:01:39 primo volumio[3564]: info: Preparing to save Alsa Options, stopping services first Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioPause Oct 17 11:01:39 primo volumio[3564]: info: CoreStateMachine::pause Oct 17 11:01:39 primo volumio[3564]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Eversolo DAC-Z8"}} Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:39 primo volumio[3564]: info: Device 5 does not have any Mixer Control Available Oct 17 11:01:39 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:39 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 17 11:01:39 primo vtcs[7288]: [2026-10-17 11:01:39.905] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 17 11:01:39 primo vtcs[7288]: [2026-10-17 11:01:39.905] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 17 11:01:39 primo volumio[3564]: info: Updating Volume Controller Parameters: Device: 5 Name: Eversolo DAC-Z8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 17 11:01:39 primo volumio[3564]: info: Disabling external Volume Control Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:01:39 primo sudo[7355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:39 primo sudo[7355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:39 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:39 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:39 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:39 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:39 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:39.963Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:39 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:39.964Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:39 primo volumio[3564]: info: Preparing to generate the ALSA configuration file Oct 17 11:01:39 primo systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 17 11:01:39 primo systemd[1]: vtcs.service: Deactivated successfully. Oct 17 11:01:39 primo systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 17 11:01:40 primo sudo[7355]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7359]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:40 primo sudo[7359]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo volumio[3564]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 11:01:40 primo volumio[3564]: info: Reading ALSA contributions from plugins. Oct 17 11:01:40 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:40 primo sudo[7366]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:40 primo sudo[7366]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:40 primo sudo[7359]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7366]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 17 11:01:40 primo sudo[7373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:40 primo sudo[7373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 17 11:01:40 primo volumio[3564]: info: Asound.conf file written Oct 17 11:01:40 primo qobuz-connect[7246]: 20261017 11:01:40.204 [7246.7246] INFO SampleApp: Stopping Local configuration server Oct 17 11:01:40 primo sudo[7376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 11:01:40 primo sudo[7376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7376]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:40 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:40 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:40 primo volumio[3564]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 17 11:01:40 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:40 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:40 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:40 primo volumio[3564]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:30fd" "" "" Oct 17 11:01:40 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:40 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:40 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:40 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:40 primo sudo[7382]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:40 primo sudo[7382]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:40 primo sudo[7383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7382]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:40 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:40 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:40 primo systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 17 11:01:40 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:40 primo sudo[7392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:40 primo sudo[7392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:40 primo sudo[7394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7394]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7398]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:40 primo sudo[7398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:40 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:40 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:40 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:40 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:40 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:40 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:40 primo sudo[7408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:40 primo sudo[7408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:40 primo sudo[7413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:40 primo sudo[7415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:40 primo sudo[7415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo qobuz-connect[7246]: 20261017 11:01:40.581 [7246.7246] INFO SampleApp: shat down connection on UNIX socket Oct 17 11:01:40 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 17 11:01:40 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:40 primo sudo[7425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:40 primo sudo[7425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:40 primo sudo[7433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:40 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:40 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:40 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:40 primo sudo[7373]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:40 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:40 primo sudo[7425]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7413]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: info: Executing endpoint qc_getconfig Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 11:01:40 primo sudo[7455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:40 primo sudo[7455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7392]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7408]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo sudo[7457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:40 primo sudo[7457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7415]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.881 [7439.7439] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.887 [7439.7439] INFO VolumeManager: [0xab994138]: Setting new playback volume: 75 Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.887 [7439.7439] INFO VolumeManager: [0xab994138]: Setting new mute state: 0 Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.889 [7439.7439] INFO AudioStreamManager: [0xab993e90]: Setting new audio download buffer size: 1048576 Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.889 [7439.7439] INFO QobuzConnect: [0xab994a00]: Client initialized! Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.889 [7439.7439] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.912 [7439.7439] INFO LocalConfigManager: [0xab993bb8]: Starting Local Configuration server Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.912 [7439.7439] INFO SampleApp: Starting Local configuration server Oct 17 11:01:40 primo qobuz-connect[7439]: 20261017 11:01:40.913 [7439.7439] INFO SampleApp: Connected to UNIX socket client 0xab97e8f8 Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:40 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:40 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:40 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:40 primo sudo[7433]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 17 11:01:40 primo volumio[3564]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 17 11:01:40 primo volumio[3564]: info: Preparing to generate the ALSA configuration file Oct 17 11:01:40 primo sudo[7473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:40 primo sudo[7473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7457]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:40 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:40 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:40 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:40 primo sudo[7475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:40 primo sudo[7475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:40 primo sudo[7476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:40 primo sudo[7476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:41 primo sudo[7478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:41 primo volumio[3564]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 11:01:41 primo volumio[3564]: info: Reading ALSA contributions from plugins. Oct 17 11:01:41 primo qobuz-connect[7439]: 20261017 11:01:41.030 [7439.7439] INFO SampleApp: Playback volume changed: 75 Oct 17 11:01:41 primo sudo[7478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:41 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:41 primo qobuz-connect[7439]: 20261017 11:01:41.040 [7439.7439] INFO SampleApp: Stopping Local configuration server Oct 17 11:01:41 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:41 primo sudo[7491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:41 primo sudo[7491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:41 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 11:01:41 primo volumio[3564]: info: Asound.conf file written Oct 17 11:01:41 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 11:01:41 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:41 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:41 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:41 primo sudo[7496]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:41 primo sudo[7496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo sudo[7498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 17 11:01:41 primo sudo[7498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo sudo[7498]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:41 primo sudo[7478]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:41 primo sudo[7473]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:41 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:41 primo volumio[3564]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Oct 17 11:01:41 primo volumio[3564]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Oct 17 11:01:41 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:41 primo volumio[3564]: No state is present for card AMLAUGESOUNDMP1 Oct 17 11:01:41 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:41 primo volumio[3564]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:30fd" "" "" Oct 17 11:01:41 primo volumio[3564]: Hardware is initialized using a generic method Oct 17 11:01:41 primo volumio[3564]: No state is present for card DACZ8 Oct 17 11:01:41 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:41 primo sudo[7476]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7491]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7496]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7513]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:41 primo sudo[7513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:41 primo sudo[7517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:41 primo sudo[7517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo sudo[7517]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:41 primo sudo[7516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:41 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo sudo[7531]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:41 primo sudo[7531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:41 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:41 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:41 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:41 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:41 primo volumio[3564]: info: Preparing to generate the ALSA configuration file Oct 17 11:01:41 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:41 primo sudo[7513]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:41 primo sudo[7543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo sudo[7551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:41 primo sudo[7551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:41 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:41 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:41 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:41 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:41 primo sudo[7516]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:41 primo volumio[3564]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 11:01:41 primo volumio[3564]: info: Reading ALSA contributions from plugins. Oct 17 11:01:41 primo volumio[3564]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 17 11:01:41 primo volumio[3564]: info: Preparing to save Alsa Options, stopping services first Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioPause Oct 17 11:01:41 primo volumio[3564]: info: CoreStateMachine::pause Oct 17 11:01:41 primo volumio[3564]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Eversolo DAC-Z8"}} Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:41 primo sudo[7563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:41 primo sudo[7563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:41 primo volumio[3564]: info: Device 5 does not have any Mixer Control Available Oct 17 11:01:41 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:41 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Oct 17 11:01:41 primo sudo[7563]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 17 11:01:41 primo volumio[3564]: info: Updating Volume Controller Parameters: Device: 5 Name: Eversolo DAC-Z8 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 17 11:01:41 primo volumio[3564]: info: Disabling external Volume Control Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 17 11:01:41 primo sudo[7573]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:41 primo sudo[7573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:41 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:41 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:41 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:41 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:41.889Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:41 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:41.889Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:41 primo sudo[7576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:41 primo sudo[7576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:41 primo sudo[7551]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7543]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:41 primo sudo[7582]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:41 primo sudo[7582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:42 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:42 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:42 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:42 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:42 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:42 primo sudo[7588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:42 primo sudo[7588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo sudo[7573]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:42 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:42 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:42 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:42 primo sudo[7582]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:42 primo sudo[7588]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo sudo[7602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:42 primo sudo[7602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo sudo[7568]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 11:01:42 primo sudo[7568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 11:01:42 primo sudo[7568]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:42 primo sudo[7614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:42 primo sudo[7614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:42 primo volumio[3564]: 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 17 11:01:42 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:42 primo volumio[3564]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 17 11:01:42 primo sudo[7614]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: Asound.conf file unchanged, so no further update is needed Oct 17 11:01:42 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:42 primo sudo[7621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:42 primo sudo[7621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo sudo[7622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:42 primo sudo[7622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:42 primo sudo[7626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:42 primo sudo[7626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo sudo[7626]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo sudo[7628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:42 primo sudo[7628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:42 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:42 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 11:01:42 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:42 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 11:01:42 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:42 primo volumio[3564]: info: Preparing to generate the ALSA configuration file Oct 17 11:01:42 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:42 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:42 primo sudo[7637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:42 primo sudo[7637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:42 primo sudo[7622]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo sudo[7640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:42 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:42 primo sudo[7621]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo sudo[7640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:42 primo sudo[7660]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:42 primo sudo[7660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:42 primo qobuz-connect[7439]: 20261017 11:01:42.920 [7439.7439] INFO SampleApp: shat down connection on UNIX socket Oct 17 11:01:42 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 17 11:01:42 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:42 primo volumio[3564]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 17 11:01:42 primo volumio[3564]: info: Reading ALSA contributions from plugins. Oct 17 11:01:42 primo sudo[7660]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:42 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:42 primo volumio[3564]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 17 11:01:42 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:42 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:42 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:42 primo sudo[7679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:42 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:42 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:42 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:42 primo sudo[7679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo sudo[7680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:43 primo sudo[7680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo sudo[7602]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7455]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7475]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7576]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo sudo[7640]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:43 primo sudo[7637]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: Not Reporting Auto name since its the default one Oct 17 11:01:43 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:43 primo sudo[7679]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:43 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:43 primo volumio[3564]: info: Executing endpoint qc_getconfig Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 11:01:43 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:43 primo sudo[7699]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:43 primo sudo[7699]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo volumio[3564]: info: Starting Shairport Sync Oct 17 11:01:43 primo qobuz-connect[7681]: 20261017 11:01:43.292 [7681.7681] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 11:01:43 primo qobuz-connect[7681]: 20261017 11:01:43.298 [7681.7681] INFO VolumeManager: [0xac9d9138]: Setting new playback volume: 75 Oct 17 11:01:43 primo qobuz-connect[7681]: 20261017 11:01:43.299 [7681.7681] INFO VolumeManager: [0xac9d9138]: Setting new mute state: 0 Oct 17 11:01:43 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:43 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 17 11:01:43 primo qobuz-connect[7681]: 20261017 11:01:43.299 [7681.7681] INFO AudioStreamManager: [0xac9d8e90]: Setting new audio download buffer size: 1048576 Oct 17 11:01:43 primo systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 17 11:01:43 primo sudo[7710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 11:01:43 primo sudo[7710]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 17 11:01:43 primo sudo[7680]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 17 11:01:43 primo volumio[3564]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Oct 17 11:01:43 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:43 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:43 primo volumio[3564]: info: Asound.conf file unchanged, so no further update is needed Oct 17 11:01:43 primo volumio[3564]: info: Output device has changed, restarting MPD Oct 17 11:01:43 primo sudo[7720]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 17 11:01:43 primo sudo[7720]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:43 primo sudo[7699]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 17 11:01:43 primo volumio[3564]: info: Output device has changed, restarting Shairport Sync Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:43 primo systemd[1]: shairport-sync.service: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:43 primo sudo[7725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 17 11:01:43 primo sudo[7725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo volumio[3564]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 11:01:43 primo volumio[3564]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo sudo[7726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 17 11:01:43 primo sudo[7726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 17 11:01:43 primo sudo[7725]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:43 primo volumio[3564]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo sudo[7710]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7720]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7744]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:43 primo sudo[7744]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo sudo[7746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 17 11:01:43 primo sudo[7746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo sudo[7747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 17 11:01:43 primo sudo[7747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo systemd[1]: mpd.service: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 17 11:01:43 primo systemd[1]: mpd.socket: Deactivated successfully. Oct 17 11:01:43 primo systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 17 11:01:43 primo systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: setDeactiveState invoked Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:43 primo volumio[3564]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:43 primo volumio[3564]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 17 11:01:43 primo sudo[7756]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 17 11:01:43 primo sudo[7756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo volumio[3564]: info: MPD Permissions set Oct 17 11:01:43 primo volumio[3564]: info: Shairport-Sync Started Oct 17 11:01:43 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:43 primo volumio[3564]: info: Executing endpoint qc_getconfig Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 17 11:01:43 primo systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 17 11:01:43 primo sudo[7774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 17 11:01:43 primo sudo[7774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:43 primo systemd[1]: Starting mpd.service - Music Player Daemon... Oct 17 11:01:43 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.861 [7718.7718] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 17 11:01:43 primo volumio[3564]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 17 11:01:43 primo volumio[3564]: info: Executing endpoint restartRAATSocket Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.879 [7718.7718] INFO VolumeManager: [0xacc62138]: Setting new playback volume: 75 Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.880 [7718.7718] INFO VolumeManager: [0xacc62138]: Setting new mute state: 0 Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.880 [7718.7718] INFO AudioStreamManager: [0xacc61e90]: Setting new audio download buffer size: 1048576 Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.881 [7718.7718] INFO QobuzConnect: [0xacc62a00]: Client initialized! Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.882 [7718.7718] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Oct 17 11:01:43 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.918 [7718.7718] INFO LocalConfigManager: [0xacc61bb8]: Starting Local Configuration server Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.918 [7718.7718] INFO SampleApp: Starting Local configuration server Oct 17 11:01:43 primo qobuz-connect[7718]: 20261017 11:01:43.919 [7718.7718] INFO SampleApp: Connected to UNIX socket client 0xacc4c8f8 Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:43 primo sudo[7756]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7747]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo sudo[7744]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 11:01:43 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 17 11:01:44 primo qobuz-connect[7718]: 20261017 11:01:44.011 [7718.7718] INFO SampleApp: Playback volume changed: 75 Oct 17 11:01:44 primo systemd[1]: Stopping raat-daemon.service - RAAT DAEMON... Oct 17 11:01:44 primo sudo[7790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 17 11:01:44 primo sudo[7790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:44 primo systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 17 11:01:44 primo sudo[7774]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:44 primo systemd[1]: raat-daemon.service: Deactivated successfully. Oct 17 11:01:44 primo systemd[1]: Stopped raat-daemon.service - RAAT DAEMON. Oct 17 11:01:44 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:44 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:44 primo volumio[3564]: info: TidalConnect service stoped! Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 17 11:01:44 primo volumio[3564]: info: CoreStateMachine::getcurrentVolume Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 11:01:44 primo volumio[3564]: info: CoreStateMachine::pushState Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::volumioPushState Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:44 primo volumio[3564]: info: MRS: Pushing multiroomSync output update for this device Oct 17 11:01:44 primo volumio[3564]: info: MRS: Pushing multiroomSync output Oct 17 11:01:44 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:44.127Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" state=STATUS_PAUSED positionMs=108846 volume=100 Oct 17 11:01:44 primo volumio5-onboarding[4140]: time=2026-10-17T09:01:44.128Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.100:64582 @ 0x2cb1500" id=qobuz://song/47683834 title="The Happiest Days of Our Lives" Oct 17 11:01:44 primo systemd[1]: Started raat-daemon.service - RAAT DAEMON. Oct 17 11:01:44 primo volumio[3564]: info: RAAT: Requesting Headphone Status Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Oct 17 11:01:44 primo sudo[7746]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:44 primo volumio[3564]: info: MCU Signalled Headphone Mode Disabled Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Oct 17 11:01:44 primo volumio[3564]: info: Reporting Headphone State: false Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 11:01:44 primo volumio[3564]: info: Updating RAAT Signal Path Oct 17 11:01:44 primo volumio[3564]: info: CoreCommandRouter::volumioGetState Oct 17 11:01:44 primo systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 17 11:01:44 primo qobuz-connect[7718]: 20261017 11:01:44.242 [7718.7718] INFO SampleApp: Stopping Local configuration server Oct 17 11:01:44 primo volumio[3564]: info: Raat Daemon started successfully Oct 17 11:01:44 primo volumio[3564]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:01:44 primo volumio[3564]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Oct 17 11:01:44 primo volumio[3564]: Job for vtcs.service canceled. Oct 17 11:01:44 primo volumio[3564]: at ChildProcess.exithandler (node:child_process:421:12) Oct 17 11:01:44 primo volumio[3564]: at ChildProcess.emit (node:events:514:28) Oct 17 11:01:44 primo volumio[3564]: at maybeClose (node:internal/child_process:1105:16) Oct 17 11:01:44 primo volumio[3564]: at Socket. (node:internal/child_process:457:11) Oct 17 11:01:44 primo volumio[3564]: at Socket.emit (node:events:514:28) Oct 17 11:01:44 primo volumio[3564]: at Pipe. (node:net:337:12) { Oct 17 11:01:44 primo volumio[3564]: code: 1, Oct 17 11:01:44 primo volumio[3564]: killed: false, Oct 17 11:01:44 primo volumio[3564]: signal: null, Oct 17 11:01:44 primo volumio[3564]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Oct 17 11:01:44 primo volumio[3564]: stdout: '', Oct 17 11:01:44 primo volumio[3564]: stderr: 'Job for vtcs.service canceled.\n' Oct 17 11:01:44 primo volumio[3564]: } Oct 17 11:01:44 primo volumio[3564]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 11:01:44 primo sudo[7778]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 17 11:01:44 primo sudo[7778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 17 11:01:44 primo sudo[7778]: pam_unix(sudo:session): session closed for user root Oct 17 11:01:45 primo sudo[7842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-17 11:00' Oct 17 11:01:45 primo sudo[7842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9ccd1247f8cab3c5d64c23a96d243f6bfa34d032" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun May 17 17:32:08 UTC 2026" VOLUMIO_VERSION="4.158" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="43d420a3aa41c50690ebfe378df38e2b"