-- Logs begin at Wed 2025-06-11 12:55:17 CEST, end at Wed 2025-06-11 17:31:38 CEST. -- Jun 11 17:30:11 primo go-librespot[18658]: time="2025-06-11T17:30:11+02:00" level=trace msg="sent dealer ping" Jun 11 17:30:11 primo go-librespot[18658]: time="2025-06-11T17:30:11+02:00" level=trace msg="received dealer pong" Jun 11 17:30:13 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:30:13 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:30:13 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:30:13 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:13 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:30:13 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:30:13 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:30:13 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 11 17:30:13 primo volumio[3210]: SPOTIFY: SPOTIFY VOLUME 100 Jun 11 17:30:13 primo volumio[3210]: SPOTIFY: VOLUMIO VOLUME 0 Jun 11 17:30:13 primo volumio[3210]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 11 17:30:13 primo volumio[3210]: info: Setting Spotify Volume from Volumio: 0 Jun 11 17:30:13 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 0 Jun 11 17:30:13 primo volumio[3210]: info: MCU Signalled Sleep Mode Active Jun 11 17:30:13 primo sudo[18100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force off Jun 11 17:30:13 primo sudo[18100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:30:13 primo sudo[18100]: pam_unix(sudo:session): session closed for user root Jun 11 17:30:14 primo volumio[3210]: info: MCU Signalled Sleep Mode Disabled Jun 11 17:30:14 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:30:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:30:14 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:30:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:30:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:30:14 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:30:14 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:30:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:30:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:30:14 primo sudo[18103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Jun 11 17:30:14 primo sudo[18103]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:30:14 primo sudo[18103]: pam_unix(sudo:session): session closed for user root Jun 11 17:30:14 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:30:14 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: SPOTIFY VOLUME 0 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: VOLUMIO VOLUME 100 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: DELTA VOLUME ENOUGH: true Jun 11 17:30:14 primo volumio[3210]: info: Setting Spotify Volume from Volumio: 100 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:30:14 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:30:14 primo sudo[18106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Jun 11 17:30:14 primo sudo[18106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:30:14 primo sudo[18106]: pam_unix(sudo:session): session closed for user root Jun 11 17:30:16 primo volumio[3210]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jun 11 17:30:16 primo volumio[3210]: info: Sending Spotify command with payload to local API: /player/volume Jun 11 17:30:16 primo go-librespot[18658]: time="2025-06-11T17:30:16+02:00" level=debug msg="update volume to 65535/65535" Jun 11 17:30:17 primo go-librespot[18658]: time="2025-06-11T17:30:17+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jun 11 17:30:17 primo go-librespot[18658]: time="2025-06-11T17:30:17+02:00" level=trace msg="emitting websocket event: volume" Jun 11 17:30:17 primo volumio[3210]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jun 11 17:30:17 primo volumio[3210]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jun 11 17:30:17 primo volumio[3210]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jun 11 17:30:17 primo volumio[3210]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jun 11 17:30:27 primo volumio[3210]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 11 17:30:28 primo volumio[3210]: verbose: New Socket.io Connection to 192.168.178.24 from 192.168.178.43 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetVisibleSources Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 17:30:28 primo volumio[3210]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 11 17:30:28 primo volumio[3210]: info: Received Get System Info Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 17:30:28 primo volumio[3210]: info: Discovery: Getting this device information Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:28 primo volumio[3210]: info: Listing playlists Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetQueue Jun 11 17:30:28 primo volumio[3210]: info: CoreStateMachine::getQueue Jun 11 17:30:28 primo volumio[3210]: info: CorePlayQueue::getQueue Jun 11 17:30:28 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 11 17:30:29 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 17:30:29 primo volumio[3210]: info: Received Get System Info Jun 11 17:30:29 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 11 17:30:29 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 11 17:30:29 primo volumio[3210]: info: Discovery: Getting this device information Jun 11 17:30:29 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:30:29 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 11 17:30:41 primo go-librespot[18658]: time="2025-06-11T17:30:41+02:00" level=trace msg="sent dealer ping" Jun 11 17:30:41 primo go-librespot[18658]: time="2025-06-11T17:30:41+02:00" level=trace msg="received dealer pong" Jun 11 17:30:58 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: Retrieving Cloud Streaming UI Jun 11 17:31:00 primo volumio[3210]: info: Getting Tidal Cloud Configuration Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: Getting Qobuz Cloud Configuration Jun 11 17:31:00 primo volumio[3210]: info: Asking plugin for UI Config Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: Getting Spotify Cloud Configuration Jun 11 17:31:00 primo volumio[3210]: info: Asking plugin for UI Config Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: Saving Spotify Acccount Jun 11 17:31:00 primo volumio[3210]: info: Got it Jun 11 17:31:00 primo volumio[3210]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 11 17:31:00 primo volumio[3210]: info: Got Tidal Cloud Configuration Jun 11 17:31:00 primo volumio[3210]: info: Got it Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:00 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:01 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 17:31:01 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 17:31:01 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:01 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:01 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:01 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:01 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:01 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:05 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 17:31:06 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:08 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:08 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:08 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:08 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:08 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:08 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:10 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:10 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:10 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:11 primo go-librespot[18658]: time="2025-06-11T17:31:11+02:00" level=trace msg="sent dealer ping" Jun 11 17:31:11 primo go-librespot[18658]: time="2025-06-11T17:31:11+02:00" level=trace msg="received dealer pong" Jun 11 17:31:14 primo volumio[3210]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 11 17:31:14 primo volumio[3210]: info: Preparing to save Alsa Options, stopping services first Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioPause Jun 11 17:31:14 primo volumio[3210]: info: CoreStateMachine::pause Jun 11 17:31:14 primo volumio[3210]: info: Saving Audio Output to: {"output_device":{"value":"0,0","label":"Analog Outputs"}} Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:14 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:14 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:14 primo volumio[3210]: info: Found match in Cards Database: setting mixer for card Analog Outputs Jun 11 17:31:14 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:14 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jun 11 17:31:14 primo sudo[18283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:14 primo sudo[18283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18283]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:14 primo volumio[3210]: info: Setting mixer Audio hdmi-out mute for card Analog Outputs Jun 11 17:31:14 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:14 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jun 11 17:31:14 primo sudo[18289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:14 primo sudo[18289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18289]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:14 primo volumio[3210]: info: Applying Volume Override Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 17:31:14 primo volumio[3210]: info: Updating Volume Controller Parameters: Device: 0,0 Name: Analog Outputs Mixer: Audio hdmi-out mute Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 11 17:31:14 primo volumio[3210]: info: Enabling external Volume Control Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , updateVolumeSettings Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , retrievevolume Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:14 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:14 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:14 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:14 primo volumio[3210]: info: Preparing to generate the ALSA configuration file Jun 11 17:31:14 primo sudo[18295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:14 primo sudo[18295]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:14 primo sudo[18298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18295]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo sudo[18298]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo sudo[18306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:14 primo sudo[18306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18306]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo sudo[18311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:14 primo sudo[18311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo sudo[18313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:14 primo sudo[18313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:14 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:14 primo sudo[18313]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:14 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:14 primo sudo[18321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:14 primo sudo[18321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo qobuz-connect[8356]: 20250611 17:31:15.013 [8356.8356] INFO SampleApp: Stopping Local configuration server Jun 11 17:31:15 primo qobuz-connect[8356]: 20250611 17:31:15.023 [8356.8356] INFO SampleApp: shat down connection on UNIX socket Jun 11 17:31:15 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:15 primo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 11 17:31:15 primo volumio[3210]: info: Reading ALSA contributions from plugins. Jun 11 17:31:15 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:15 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:15 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:15 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:15 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:15 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:15 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:15 primo volumio[3210]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Jun 11 17:31:15 primo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 11 17:31:15 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:15 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:15 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:15 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:15 primo sudo[18311]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo sudo[18321]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:15 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:15 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:15 primo sudo[18343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:15 primo sudo[18343]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo sudo[18343]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo sudo[18347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:15 primo sudo[18347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:15 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:15 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:15 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:15 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:15 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:15 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:15 primo sudo[18347]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo sudo[18351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:15 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:15 primo sudo[18351]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:15 primo kernel: aml_tdm_open Jun 11 17:31:15 primo kernel: Not init audio effects Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo kernel: aml_tdm_open Jun 11 17:31:15 primo kernel: Not init audio effects Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo sudo[18351]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.655 [18327.18327] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 11 17:31:15 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:15 primo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 11 17:31:15 primo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.673 [18327.18327] INFO VolumeManager: [0xac64e800]: Setting new playback volume: 75 Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.674 [18327.18327] INFO VolumeManager: [0xac64e800]: Setting new mute state: 0 Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.675 [18327.18327] INFO QobuzConnect: [0xac64f1d0]: Client initialized! Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.675 [18327.18327] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 11 17:31:15 primo sudo[18362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:15 primo sudo[18362]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.697 [18327.18327] INFO LocalConfigManager: [0xac64e0e0]: Starting Local Configuration server Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.697 [18327.18327] INFO SampleApp: Starting Local configuration server Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.698 [18327.18327] INFO SampleApp: Connected to UNIX socket client 0xac643ed8 Jun 11 17:31:15 primo volumio[3210]: info: Asound.conf file written Jun 11 17:31:15 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:15 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:15 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:15 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:15 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:15 primo sudo[18362]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo sudo[18370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 11 17:31:15 primo sudo[18370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo sudo[18370]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo qobuz-connect[18327]: 20250611 17:31:15.804 [18327.18327] INFO SampleApp: Playback volume changed: 75 Jun 11 17:31:15 primo kernel: aml_tdm_open Jun 11 17:31:15 primo kernel: Not init audio effects Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo kernel: aml_tdm_open Jun 11 17:31:15 primo kernel: Not init audio effects Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:15 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:15 primo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Jun 11 17:31:15 primo volumio[3210]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jun 11 17:31:15 primo volumio[3210]: Hardware is initialized using a generic method Jun 11 17:31:15 primo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Jun 11 17:31:15 primo volumio[3210]: info: Output device has changed, restarting MPD Jun 11 17:31:15 primo sudo[18389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 17:31:15 primo sudo[18389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:15 primo sudo[18389]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:15 primo volumio[3210]: info: Output device has changed, restarting Shairport Sync Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:15 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:15 primo sudo[18392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 17:31:15 primo sudo[18392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo systemd[1]: Stopping Music Player Daemon... Jun 11 17:31:16 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:16 primo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 17:31:16 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:16 primo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo kernel: aml_tdm_open Jun 11 17:31:16 primo kernel: Not init audio effects Jun 11 17:31:16 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:16 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:16 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:16 primo volumio[3210]: info: Output device has changed, restarting MPD Jun 11 17:31:16 primo sudo[18401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:16 primo sudo[18401]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18401]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo sudo[18404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 17:31:16 primo sudo[18404]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18404]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo volumio[3210]: info: Output device has changed, restarting Shairport Sync Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:16 primo sudo[18408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 17:31:16 primo sudo[18408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:16 primo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 17:31:16 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:16 primo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo kernel: aml_tdm_open Jun 11 17:31:16 primo kernel: Not init audio effects Jun 11 17:31:16 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:16 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:16 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:16 primo sudo[18417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:16 primo sudo[18417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:16 primo sudo[18420]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18417]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo sudo[18424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:16 primo sudo[18424]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18420]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo sudo[18424]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo sudo[18432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:16 primo sudo[18432]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo sudo[18432]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo sudo[18437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:16 primo sudo[18437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo qobuz-connect[18327]: 20250611 17:31:16.661 [18327.18327] INFO SampleApp: Stopping Local configuration server Jun 11 17:31:16 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:16 primo sudo[18440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:16 primo sudo[18440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo qobuz-connect[18327]: 20250611 17:31:16.671 [18327.18327] INFO SampleApp: shat down connection on UNIX socket Jun 11 17:31:16 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:16 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:16 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:16 primo sudo[18437]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:16 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:16 primo sudo[18440]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:16 primo sudo[18447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:16 primo sudo[18447]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: MPD Permissions set Jun 11 17:31:16 primo volumio[3210]: info: MPD Permissions set Jun 11 17:31:16 primo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 11 17:31:16 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:16 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:16 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:16 primo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jun 11 17:31:16 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:16 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:16 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:16 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:16 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:16 primo sudo[18447]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:16 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:16 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:16 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:16 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:16 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:16 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:16 primo systemd[1]: mpd.service: Succeeded. Jun 11 17:31:16 primo systemd[1]: Stopped Music Player Daemon. Jun 11 17:31:16 primo systemd[1]: Starting Music Player Daemon... Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:17 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:17 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:17 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:17 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:17 primo sudo[18456]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 17:31:17 primo sudo[18456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo sudo[18456]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo sudo[18465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:17 primo sudo[18465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo sudo[18465]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 11 17:31:17 primo sudo[18471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:17 primo sudo[18471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:17 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:17 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:17 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:17 primo sudo[18471]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:17 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.493 [18451.18451] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.499 [18451.18451] INFO VolumeManager: [0xac5c3800]: Setting new playback volume: 75 Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.499 [18451.18451] INFO VolumeManager: [0xac5c3800]: Setting new mute state: 0 Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.499 [18451.18451] INFO QobuzConnect: [0xac5c41d0]: Client initialized! Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.499 [18451.18451] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:17 primo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 11 17:31:17 primo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.516 [18451.18451] INFO LocalConfigManager: [0xac5c30e0]: Starting Local Configuration server Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.516 [18451.18451] INFO SampleApp: Starting Local configuration server Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.516 [18451.18451] INFO SampleApp: Connected to UNIX socket client 0xac5b8ed8 Jun 11 17:31:17 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:17 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:17 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:17 primo qobuz-connect[18451]: 20250611 17:31:17.642 [18451.18451] INFO SampleApp: Playback volume changed: 75 Jun 11 17:31:17 primo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 17:31:17 primo volumio[3210]: info: Starting Shairport Sync Jun 11 17:31:17 primo sudo[18492]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:17 primo sudo[18492]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo sudo[18492]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo volumio[3210]: info: Starting Shairport Sync Jun 11 17:31:17 primo sudo[18496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:17 primo sudo[18495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 17:31:17 primo sudo[18496]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo sudo[18495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:17 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:17 primo sudo[18504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 17:31:17 primo sudo[18504]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:17 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 17:31:17 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:17 primo systemd[1]: shairport-sync.service: Succeeded. Jun 11 17:31:17 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:17 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:17 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:17 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:17 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:17 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:17 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:17 primo sudo[18496]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:17 primo sudo[18495]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:17 primo kernel: aml_tdm_open Jun 11 17:31:17 primo kernel: Not init audio effects Jun 11 17:31:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:17 primo kernel: aml_tdm_open Jun 11 17:31:17 primo kernel: Not init audio effects Jun 11 17:31:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 11 17:31:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 11 17:31:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 11 17:31:17 primo volumio[3210]: info: Shairport-Sync Started Jun 11 17:31:17 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:17 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 17:31:18 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:18 primo systemd[1]: shairport-sync.service: Succeeded. Jun 11 17:31:18 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:18 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:18 primo sudo[18504]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:18 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:18 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:18 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:18 primo volumio[3210]: info: Shairport-Sync Started Jun 11 17:31:18 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:18 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:18 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:18 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:18 primo sudo[18562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:18 primo sudo[18562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:18 primo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 17:31:18 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:18 primo sudo[18562]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:18 primo sudo[18565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:18 primo sudo[18565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:18 primo sudo[18565]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:18 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:18 primo volumio[3210]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 11 17:31:18 primo volumio[3210]: info: Preparing to save Alsa Options, stopping services first Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioPause Jun 11 17:31:18 primo volumio[3210]: info: CoreStateMachine::pause Jun 11 17:31:18 primo volumio[3210]: info: Saving Audio Output to: {"output_device":{"value":"0,2","label":"S/PDIF"}} Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:18 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:18 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:18 primo volumio[3210]: info: Found match in Cards Database for ignoring default Mixer Jun 11 17:31:18 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:18 primo volumio[3210]: info: Setting mixer Audio hdmi-out mute for card S/PDIF Jun 11 17:31:18 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:18 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jun 11 17:31:18 primo volumio[3210]: info: Ignoring Mixers Options Jun 11 17:31:18 primo volumio[3210]: info: Device 0 does not have any Mixer Control Available Jun 11 17:31:18 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:18 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Jun 11 17:31:18 primo sudo[18578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:18 primo sudo[18578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:18 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 11 17:31:18 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:18 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:18 primo sudo[18582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:18 primo sudo[18582]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:18 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:18 primo sudo[18578]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:18 primo sudo[18582]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:18 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 17:31:18 primo volumio[3210]: 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 Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 11 17:31:18 primo volumio[3210]: info: Disabling external Volume Control Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:18 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:18 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:18 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:18 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:18 primo volumio[3210]: info: Preparing to generate the ALSA configuration file Jun 11 17:31:19 primo sudo[18591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:19 primo sudo[18591]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo sudo[18594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:19 primo sudo[18594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo sudo[18591]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo sudo[18594]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo sudo[18602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:19 primo sudo[18602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo sudo[18602]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo sudo[18607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:19 primo sudo[18607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 17:31:19 primo volumio[3210]: info: CoreStateMachine::getcurrentVolume Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 17:31:19 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:19 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:19 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:19 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:19 primo sudo[18609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:19 primo sudo[18609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo qobuz-connect[18451]: 20250611 17:31:19.246 [18451.18451] INFO SampleApp: Stopping Local configuration server Jun 11 17:31:19 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:19 primo qobuz-connect[18451]: 20250611 17:31:19.257 [18451.18451] INFO SampleApp: shat down connection on UNIX socket Jun 11 17:31:19 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:19 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:19 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:19 primo sudo[18607]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo sudo[18609]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:19 primo sudo[18617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:19 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:19 primo sudo[18617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:19 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:19 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:19 primo volumio[3210]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 11 17:31:19 primo volumio[3210]: info: Reading ALSA contributions from plugins. Jun 11 17:31:19 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:19 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:19 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:19 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:19 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:19 primo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jun 11 17:31:19 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:19 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:19 primo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 11 17:31:19 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:19 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:19 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:19 primo sudo[18617]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:19 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:19 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:19 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:19 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:19 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:19 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:19 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:19 primo mpd[18464]: Jun 11 17:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 11 17:31:19 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:19 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:19 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:19 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.902 [18620.18620] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.913 [18620.18620] INFO VolumeManager: [0xabd8a800]: Setting new playback volume: 75 Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.913 [18620.18620] INFO VolumeManager: [0xabd8a800]: Setting new mute state: 0 Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.913 [18620.18620] INFO QobuzConnect: [0xabd8b1d0]: Client initialized! Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.913 [18620.18620] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.949 [18620.18620] INFO LocalConfigManager: [0xabd8a0e0]: Starting Local Configuration server Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.949 [18620.18620] INFO SampleApp: Starting Local configuration server Jun 11 17:31:19 primo qobuz-connect[18620]: 20250611 17:31:19.950 [18620.18620] INFO SampleApp: Connected to UNIX socket client 0xabd7fed8 Jun 11 17:31:20 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 17:31:20 primo qobuz-connect[18620]: 20250611 17:31:20.055 [18620.18620] INFO SampleApp: Playback volume changed: 75 Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 17:31:20 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:20 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:20 primo volumio[3210]: [VRCP] RAAT stopped playing Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:20 primo sudo[18651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:20 primo volumio[3210]: info: CorePlayQueue::getTrack 0 Jun 11 17:31:20 primo volumio[3210]: verbose: STATE SERVICE {"status":"stop","service":"raat","title":"Swallowed","artist":"Threshold","album":"Legends of the Shires","albumart":"/albumart?web=100/extralarge&path=/tmp/raat/albumart/6b5d0cccba9f93ab9baad03a29d1c4f0.jpg","uri":"","trackType":"","seek":null,"duration":234,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":false,"push_time":1749640371811,"random":false,"repeat":false,"repeatSingle":false} Jun 11 17:31:20 primo volumio[3210]: verbose: CURRENT POSITION 0 Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::syncState stateService stop Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::syncState currentStatus pause Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:20 primo sudo[18651]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:20 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:20 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:20 primo sudo[18651]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo sudo[18653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:20 primo sudo[18653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 11 17:31:20 primo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 11 17:31:20 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:20 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:20 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:20 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:20 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:20 primo sudo[18653]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:20 primo kernel: aml_spdif_open Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: spdif_a keep clk continuous Jun 11 17:31:20 primo kernel: aml_spdif_close Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: aml_spdif_open Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: spdif_a keep clk continuous Jun 11 17:31:20 primo kernel: aml_spdif_close Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:20 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:20 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:20 primo sudo[18682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:20 primo sudo[18682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo sudo[18682]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:20 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:20 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:20 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:20 primo sudo[18686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:20 primo sudo[18686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:20 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:20 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:20 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:20 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:20 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:20 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:20 primo sudo[18686]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:20 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:20 primo sudo[18689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:20 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:20 primo sudo[18689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo kernel: aml_spdif_open Jun 11 17:31:20 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: spdif_a keep clk continuous Jun 11 17:31:20 primo kernel: aml_spdif_close Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: aml_spdif_open Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo kernel: spdif_a keep clk continuous Jun 11 17:31:20 primo kernel: aml_spdif_close Jun 11 17:31:20 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:20 primo sudo[18689]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:20 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:20 primo sudo[18703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:20 primo sudo[18703]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:20 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:20 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:20 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:20 primo sudo[18703]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:20 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:20 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:20 primo volumio[3210]: info: Asound.conf file written Jun 11 17:31:21 primo sudo[18712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 11 17:31:21 primo sudo[18712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18712]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:21 primo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Jun 11 17:31:21 primo volumio[3210]: Found hardware: "AML-AUGESOUND-M" "" "" "" "" Jun 11 17:31:21 primo volumio[3210]: Hardware is initialized using a generic method Jun 11 17:31:21 primo volumio[3210]: No state is present for card AMLAUGESOUNDMP1 Jun 11 17:31:21 primo volumio[3210]: info: Output device has changed, restarting MPD Jun 11 17:31:21 primo sudo[18718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 17:31:21 primo sudo[18718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18718]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: info: Output device has changed, restarting Shairport Sync Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:21 primo sudo[18722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 17:31:21 primo sudo[18722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:21 primo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 17:31:21 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:21 primo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:21 primo systemd[1]: mpd.service: Succeeded. Jun 11 17:31:21 primo systemd[1]: Stopped Music Player Daemon. Jun 11 17:31:21 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:21 primo systemd[1]: Starting Music Player Daemon... Jun 11 17:31:21 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo volumio[3210]: info: Output device has changed, restarting MPD Jun 11 17:31:21 primo kernel: aml_spdif_open Jun 11 17:31:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:21 primo kernel: spdif_a keep clk continuous Jun 11 17:31:21 primo kernel: aml_spdif_close Jun 11 17:31:21 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:21 primo sudo[18732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:21 primo sudo[18732]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 11 17:31:21 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:21 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:21 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:21 primo sudo[18732]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 17:31:21 primo sudo[18737]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18737]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: info: Output device has changed, restarting Shairport Sync Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:21 primo sudo[18741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 17:31:21 primo sudo[18741]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18729]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 17:31:21 primo sudo[18729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18729]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:21 primo volumio[3210]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 17:31:21 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:21 primo volumio[3210]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 11 17:31:21 primo systemd[1]: mpd.service: Succeeded. Jun 11 17:31:21 primo systemd[1]: Stopped Music Player Daemon. Jun 11 17:31:21 primo systemd[1]: Starting Music Player Daemon... Jun 11 17:31:21 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:21 primo volumio[3210]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo kernel: aml_spdif_open Jun 11 17:31:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:21 primo kernel: spdif_a keep clk continuous Jun 11 17:31:21 primo kernel: aml_spdif_close Jun 11 17:31:21 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:21 primo sudo[18750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:21 primo sudo[18750]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18750]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:21 primo sudo[18756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 11 17:31:21 primo sudo[18760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18747]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 17:31:21 primo sudo[18756]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18747]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18760]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:21 primo sudo[18770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18770]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo sudo[18775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:21 primo sudo[18775]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo sudo[18778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 11 17:31:21 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:21 primo qobuz-connect[18620]: 20250611 17:31:21.904 [18620.18620] INFO SampleApp: Stopping Local configuration server Jun 11 17:31:21 primo sudo[18778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:21 primo qobuz-connect[18620]: 20250611 17:31:21.914 [18620.18620] INFO SampleApp: shat down connection on UNIX socket Jun 11 17:31:21 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:21 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:21 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:21 primo sudo[18775]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:21 primo sudo[18778]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:21 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:21 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:21 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:21 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:21 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:22 primo sudo[18785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 11 17:31:22 primo sudo[18785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:22 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:22 primo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jun 11 17:31:22 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 11 17:31:22 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 11 17:31:22 primo volumio[3210]: info: MPD Permissions set Jun 11 17:31:22 primo volumio[3210]: info: MPD Permissions set Jun 11 17:31:22 primo volumio[3210]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 11 17:31:22 primo volumio[3210]: info: QobuzConnect: setDeactiveState invoked Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:22 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 11 17:31:22 primo sudo[18785]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: Not Reporting Auto name since its the default one Jun 11 17:31:22 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:22 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:22 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:22 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:22 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:22 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:22 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:22 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:22 primo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:22 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 11 17:31:22 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:22 primo volumio[3210]: info: Executing endpoint qc_getconfig Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.453 [18788.18788] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.460 [18788.18788] INFO VolumeManager: [0xac3e6800]: Setting new playback volume: 75 Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.460 [18788.18788] INFO VolumeManager: [0xac3e6800]: Setting new mute state: 0 Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.460 [18788.18788] INFO QobuzConnect: [0xac3e71d0]: Client initialized! Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.460 [18788.18788] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.476 [18788.18788] INFO LocalConfigManager: [0xac3e60e0]: Starting Local Configuration server Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.476 [18788.18788] INFO SampleApp: Starting Local configuration server Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.477 [18788.18788] INFO SampleApp: Connected to UNIX socket client 0xac3dbed8 Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo qobuz-connect[18788]: 20250611 17:31:22.603 [18788.18788] INFO SampleApp: Playback volume changed: 75 Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:22 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:22 primo volumio[3210]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 11 17:31:22 primo volumio[3210]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 11 17:31:22 primo sudo[18825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:22 primo sudo[18825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:22 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 11 17:31:22 primo sudo[18825]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:22 primo sudo[18831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:22 primo sudo[18831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:22 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:22 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:22 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:22 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:22 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:22 primo sudo[18831]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:22 primo volumio[3210]: xcb_connection_has_error() returned true Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:23 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:23 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:23 primo sudo[18842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 11 17:31:23 primo sudo[18842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo kernel: aml_spdif_open Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: spdif_a keep clk continuous Jun 11 17:31:23 primo kernel: aml_spdif_close Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: aml_spdif_open Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: spdif_a keep clk continuous Jun 11 17:31:23 primo kernel: aml_spdif_close Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo sudo[18842]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo sudo[18851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 11 17:31:23 primo sudo[18851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 17:31:23 primo volumio[3210]: info: CoreStateMachine::getcurrentVolume Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 17:31:23 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:23 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:23 primo systemd[1]: Stopping RAAT DAEMON... Jun 11 17:31:23 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:23 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:23 primo systemd[1]: raat-daemon.service: Succeeded. Jun 11 17:31:23 primo systemd[1]: Stopped RAAT DAEMON. Jun 11 17:31:23 primo sudo[18854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:23 primo sudo[18854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo systemd[1]: Started RAAT DAEMON. Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:23 primo sudo[18851]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:23 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:23 primo sudo[18854]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo kernel: aml_spdif_open Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: spdif_a keep clk continuous Jun 11 17:31:23 primo kernel: aml_spdif_close Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: aml_spdif_open Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo kernel: spdif_a keep clk continuous Jun 11 17:31:23 primo kernel: aml_spdif_close Jun 11 17:31:23 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 11 17:31:23 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:23 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:23 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:23 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:23 primo volumio[3210]: info: Starting Shairport Sync Jun 11 17:31:23 primo sudo[18872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:23 primo sudo[18872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo volumio[3210]: info: Starting Shairport Sync Jun 11 17:31:23 primo sudo[18875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 17:31:23 primo sudo[18875]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo sudo[18872]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:23 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 17:31:23 primo sudo[18878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 17:31:23 primo sudo[18878]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:23 primo systemd[1]: shairport-sync.service: Succeeded. Jun 11 17:31:23 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:23 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:23 primo volumio[3210]: info: Raat Daemon started successfully Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:23 primo volumio[3210]: info: Executing endpoint restartRAATSocket Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 11 17:31:23 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:23 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:23 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:23 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:23 primo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 11 17:31:23 primo sudo[18875]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo volumio[3210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 11 17:31:23 primo volumio[3210]: info: Shairport-Sync Started Jun 11 17:31:23 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 17:31:23 primo volumio[3210]: (node:3210) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 11 17:31:23 primo volumio[3210]: (node:3210) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 11 17:31:23 primo volumio[3210]: (node:3210) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 11 17:31:23 primo volumio[3210]: (node:3210) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 11 17:31:23 primo volumio[3210]: (node:3210) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 11 17:31:23 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 11 17:31:23 primo systemd[1]: shairport-sync.service: Succeeded. Jun 11 17:31:23 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:23 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 11 17:31:23 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 17:31:23 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:23 primo sudo[18878]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:23 primo volumio[3210]: info: Shairport-Sync Started Jun 11 17:31:23 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:23 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:23 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:23 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:23 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:23 primo vtcs[18861]: BYE Jun 11 17:31:23 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:23 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:23 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:23 primo systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Jun 11 17:31:23 primo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. Jun 11 17:31:23 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:23 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:24 primo mpd[18769]: Jun 11 17:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 11 17:31:24 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:24 primo vtcs[18887]: BYE Jun 11 17:31:24 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:24 primo systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Jun 11 17:31:24 primo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. Jun 11 17:31:24 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:24 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:24 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:24 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:24 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:24 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::servicePushState Jun 11 17:31:24 primo volumio[3210]: info: CoreStateMachine::pushState Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::volumioPushState Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:24 primo volumio[3210]: info: MRS: Pushing multiroomSync output update for this device Jun 11 17:31:24 primo volumio[3210]: info: MRS: Pushing multiroomSync output Jun 11 17:31:24 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:24 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:24 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:24 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:24 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:24 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:24 primo vtcs[18891]: BYE Jun 11 17:31:24 primo volumio[3210]: info: TidalConnect service stoped! Jun 11 17:31:24 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:24 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:24 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:25 primo volumio[3210]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:25 primo volumio[3210]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 11 17:31:25 primo sudo[18915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:25 primo sudo[18915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:25 primo systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Jun 11 17:31:25 primo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. Jun 11 17:31:25 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:25 primo sudo[18918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 11 17:31:25 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:25 primo sudo[18918]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:25 primo sudo[18915]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:25 primo sudo[18918]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:25 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:25 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:25 primo vtcs[18919]: BYE Jun 11 17:31:25 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:25 primo systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Jun 11 17:31:25 primo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. Jun 11 17:31:25 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:25 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 11 17:31:25 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:25 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:25 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:25 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:25 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:25 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:25 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:25 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:26 primo volumio[3210]: info: Executing endpoint tc_getconfig Jun 11 17:31:26 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 11 17:31:26 primo vtcs[18926]: BYE Jun 11 17:31:26 primo volumio[3210]: info: RAAT: Requesting Headphone Status Jun 11 17:31:26 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 11 17:31:26 primo systemd[1]: vtcs.service: Succeeded. Jun 11 17:31:26 primo volumio[3210]: info: MCU Signalled Headphone Mode Disabled Jun 11 17:31:26 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 11 17:31:26 primo volumio[3210]: info: Reporting Headphone State: false Jun 11 17:31:26 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:26 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 17:31:26 primo volumio[3210]: info: Updating RAAT Signal Path Jun 11 17:31:26 primo systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. Jun 11 17:31:26 primo systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. Jun 11 17:31:26 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 11 17:31:26 primo systemd[1]: vtcs.service: Start request repeated too quickly. Jun 11 17:31:26 primo systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. Jun 11 17:31:26 primo systemd[1]: Failed to start Volumio Tidal Connect Service. Jun 11 17:31:26 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:26 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:26 primo systemd[1]: Started Music Player Daemon. Jun 11 17:31:26 primo sudo[18408]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:26 primo sudo[18741]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:26 primo sudo[18722]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:26 primo sudo[18392]: pam_unix(sudo:session): session closed for user root Jun 11 17:31:27 primo volumio[3210]: error: MPD error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: MPD error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: MPD error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: The expression evaluated to a falsy value: Jun 11 17:31:27 primo volumio[3210]: assert.ok(self.idling) Jun 11 17:31:27 primo volumio[3210]: error: updateQueue error: null Jun 11 17:31:28 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:28 primo volumio[3210]: info: TidalConnect service started! Jun 11 17:31:28 primo volumio[3210]: info: CoreCommandRouter::volumioGetState Jun 11 17:31:30 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:30 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:30 primo volumio[3210]: info: Executing endpoint metavolumio Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: Retrieving Cloud Streaming UI Jun 11 17:31:30 primo volumio[3210]: info: Getting Tidal Cloud Configuration Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: Getting Qobuz Cloud Configuration Jun 11 17:31:30 primo volumio[3210]: info: Asking plugin for UI Config Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: Getting Spotify Cloud Configuration Jun 11 17:31:30 primo volumio[3210]: info: Asking plugin for UI Config Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: Saving Spotify Acccount Jun 11 17:31:30 primo volumio[3210]: info: Got it Jun 11 17:31:30 primo volumio[3210]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 11 17:31:30 primo volumio[3210]: info: Got Tidal Cloud Configuration Jun 11 17:31:30 primo volumio[3210]: info: Got it Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:30 primo volumio[3210]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 17:31:31 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 17:31:31 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 17:31:34 primo volumio[3210]: info: Disabling MyMusic plugin upnp Jun 11 17:31:34 primo sudo[19003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 11 17:31:34 primo sudo[19003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 17:31:34 primo systemd[1]: Stopping UPnP Renderer front-end to MPD... Jun 11 17:31:34 primo volumio[3210]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 11 17:31:34 primo volumio[3210]: error: Upnp client error: Error: This socket has been ended by the other party Jun 11 17:31:35 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 17:31:37 primo volumio[3210]: info: Enabling MyMusic plugin upnp Jun 11 17:31:37 primo volumio[3210]: info: Enabling plugin upnp Jun 11 17:31:37 primo volumio[3210]: info: Loading plugin "upnp"... Jun 11 17:31:37 primo volumio[3210]: info: [1749655897397] Starting Upmpd Daemon Jun 11 17:31:37 primo volumio[3210]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 17:31:37 primo volumio[3210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 17:31:37 primo volumio[3210]: Error: listen EADDRINUSE: address already in use :::6599 Jun 11 17:31:37 primo volumio[3210]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jun 11 17:31:37 primo volumio[3210]: at listenInCluster (net.js:1379:12) Jun 11 17:31:37 primo volumio[3210]: at Server.listen (net.js:1465:7) Jun 11 17:31:37 primo volumio[3210]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 11 17:31:37 primo volumio[3210]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 11 17:31:37 primo volumio[3210]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 11 17:31:37 primo volumio[3210]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 11 17:31:37 primo volumio[3210]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jun 11 17:31:37 primo volumio[3210]: code: 'EADDRINUSE', Jun 11 17:31:37 primo volumio[3210]: errno: -98, Jun 11 17:31:37 primo volumio[3210]: syscall: 'listen', Jun 11 17:31:37 primo volumio[3210]: address: '::', Jun 11 17:31:37 primo volumio[3210]: port: 6599 Jun 11 17:31:37 primo volumio[3210]: } Jun 11 17:31:37 primo volumio[3210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 17:31:38 primo sudo[19031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 17:30 Jun 11 17:31:38 primo sudo[19031]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 07:02:33 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="905581800126f97a879a13768b481efc"