-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2025-06-02 16:01:03 CEST. -- Jun 02 16:00:00 primo volumio[3107]: info: Disabling MyMusic plugin upnp_browser Jun 02 16:00:00 primo volumio[3107]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesMedienserver Jun 02 16:00:00 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 16:00:00 primo volumio[3107]: Cannot find translation for source Spotify Jun 02 16:00:00 primo volumio[3107]: info: Disabling plugin upnp_browser Jun 02 16:00:00 primo volumio[3107]: info: Done. Jun 02 16:00:02 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:02 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:02 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:02 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:02 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:02 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:02 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:02 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:02 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:02 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:03 primo volumio[3107]: info: Disabling MyMusic plugin multiroom Jun 02 16:00:03 primo volumio[3107]: info: Disabling plugin multiroom Jun 02 16:00:03 primo volumio[3107]: info: Preparing to generate the ALSA configuration file Jun 02 16:00:03 primo volumio[3107]: info: Asound.conf file written Jun 02 16:00:03 primo sudo[9333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 02 16:00:03 primo sudo[9333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo sudo[9333]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo kernel: Fine tdm clk setting range (0~2000000), 11289593 Jun 02 16:00:03 primo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Jun 02 16:00:03 primo kernel: out of value, fixed it Jun 02 16:00:03 primo kernel: id=0 set inskew=0 Jun 02 16:00:03 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 02 16:00:03 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 02 16:00:03 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 02 16:00:03 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 02 16:00:03 primo volumio[3107]: info: Output device has changed, restarting MPD Jun 02 16:00:03 primo sudo[9338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 16:00:03 primo sudo[9338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:03 primo volumio[3107]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: Output device has changed, restarting Shairport Sync Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:03 primo sudo[9338]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo sudo[9341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 16:00:03 primo sudo[9341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo systemd[1]: Stopping Music Player Daemon... Jun 02 16:00:03 primo volumio[3107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 16:00:03 primo volumio[3107]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo systemd[1]: mpd.service: Succeeded. Jun 02 16:00:03 primo systemd[1]: Stopped Music Player Daemon. Jun 02 16:00:03 primo systemd[1]: Starting Music Player Daemon... Jun 02 16:00:03 primo volumio[3107]: info: Done. Jun 02 16:00:03 primo volumio[3107]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 02 16:00:03 primo volumio[3107]: info: MPD Permissions set Jun 02 16:00:03 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:03 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:03 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:03 primo sudo[9361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 16:00:03 primo sudo[9361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:03 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:03 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo sudo[9361]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 16:00:03 primo sudo[9365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 16:00:03 primo sudo[9365]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo sudo[9347]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 16:00:03 primo sudo[9347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo sudo[9347]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 16:00:03 primo qobuz-connect[6446]: 20250602 16:00:03.568 [6446.6446] INFO SampleApp: Stopping Local configuration server Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:03 primo qobuz-connect[6446]: 20250602 16:00:03.579 [6446.6446] INFO SampleApp: shat down connection on UNIX socket Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:03 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 02 16:00:03 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:03 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:03 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 16:00:03 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:03 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:03 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:03 primo volumio[3107]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 16:00:03 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:03 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 16:00:03 primo volumio[3107]: info: Starting Shairport Sync Jun 02 16:00:03 primo sudo[9365]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo volumio[3107]: info: Preparing to generate the ALSA configuration file Jun 02 16:00:03 primo sudo[9380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 16:00:03 primo sudo[9380]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:03 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:03 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:03 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:03 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:03 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:03 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:03 primo volumio[3107]: info: Asound.conf file unchanged, so no further update is needed Jun 02 16:00:03 primo volumio[3107]: info: Output device has changed, restarting MPD Jun 02 16:00:03 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 02 16:00:03 primo sudo[9380]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo sudo[9385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 16:00:03 primo sudo[9385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo sudo[9385]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:03 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:03 primo volumio[3107]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: Output device has changed, restarting Shairport Sync Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:03 primo sudo[9390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 16:00:03 primo sudo[9390]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:03 primo volumio[3107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 16:00:03 primo volumio[3107]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 16:00:03 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:03 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 02 16:00:03 primo systemd[1]: mpd.service: Succeeded. Jun 02 16:00:03 primo systemd[1]: Stopped Music Player Daemon. Jun 02 16:00:03 primo systemd[1]: Starting Music Player Daemon... Jun 02 16:00:04 primo sudo[9406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 16:00:04 primo sudo[9406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: Not Reporting Auto name since its the default one Jun 02 16:00:04 primo sudo[9406]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 02 16:00:04 primo volumio[3107]: info: MPD Permissions set Jun 02 16:00:04 primo volumio[3107]: info: Shairport-Sync Started Jun 02 16:00:04 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:04 primo sudo[9398]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 16:00:04 primo sudo[9398]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:04 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:04 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:04 primo sudo[9398]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: info: Executing endpoint qc_getconfig Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 16:00:04 primo sudo[9412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 16:00:04 primo sudo[9412]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.093 [9375.9375] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 16:00:04 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.104 [9375.9375] INFO VolumeManager: [0xabbcd800]: Setting new playback volume: 75 Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.104 [9375.9375] INFO VolumeManager: [0xabbcd800]: Setting new mute state: 0 Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.104 [9375.9375] INFO QobuzConnect: [0xabbce1d0]: Client initialized! Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.104 [9375.9375] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:04 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:04 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:04 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.119 [9375.9375] INFO LocalConfigManager: [0xabbcd0e0]: Starting Local Configuration server Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.119 [9375.9375] INFO SampleApp: Starting Local configuration server Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.120 [9375.9375] INFO SampleApp: Connected to UNIX socket client 0xabbc2ed8 Jun 02 16:00:04 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:04 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:04 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:04 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:04 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 16:00:04 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.139 [9375.9375] INFO SampleApp: Stopping Local configuration server Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo qobuz-connect[9375]: 20250602 16:00:04.149 [9375.9375] INFO SampleApp: shat down connection on UNIX socket Jun 02 16:00:04 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 02 16:00:04 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 16:00:04 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 16:00:04 primo sudo[9412]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 16:00:04 primo sudo[9428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 02 16:00:04 primo sudo[9428]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 16:00:04 primo volumio[3107]: info: Not Reporting Auto name since its the default one Jun 02 16:00:04 primo sudo[9428]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:04 primo volumio[3107]: info: Executing endpoint qc_getconfig Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 16:00:04 primo volumio[3107]: info: Starting Shairport Sync Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.401 [9418.9418] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 16:00:04 primo sudo[9434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.408 [9418.9418] INFO VolumeManager: [0xaaf21800]: Setting new playback volume: 75 Jun 02 16:00:04 primo sudo[9434]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.411 [9418.9418] INFO VolumeManager: [0xaaf21800]: Setting new mute state: 0 Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.411 [9418.9418] INFO QobuzConnect: [0xaaf221d0]: Client initialized! Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.411 [9418.9418] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.429 [9418.9418] INFO LocalConfigManager: [0xaaf210e0]: Starting Local Configuration server Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.429 [9418.9418] INFO SampleApp: Starting Local configuration server Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.430 [9418.9418] INFO SampleApp: Connected to UNIX socket client 0xaaf16ed8 Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 02 16:00:04 primo systemd[1]: Stopping RAAT DAEMON... Jun 02 16:00:04 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jun 02 16:00:04 primo kernel: spdif_a keep clk continuous Jun 02 16:00:04 primo kernel: aml_spdif_close Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 02 16:00:04 primo systemd[1]: raat-daemon.service: Succeeded. Jun 02 16:00:04 primo systemd[1]: Stopped RAAT DAEMON. Jun 02 16:00:04 primo systemd[1]: Started RAAT DAEMON. Jun 02 16:00:04 primo sudo[9438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 16:00:04 primo sudo[9438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo sudo[9434]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 02 16:00:04 primo systemd[1]: shairport-sync.service: Succeeded. Jun 02 16:00:04 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 02 16:00:04 primo kernel: aml_spdif_open Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: spdif_a keep clk continuous Jun 02 16:00:04 primo kernel: aml_spdif_close Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: aml_spdif_open Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: spdif_a keep clk continuous Jun 02 16:00:04 primo kernel: aml_spdif_close Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo qobuz-connect[9418]: 20250602 16:00:04.552 [9418.9418] INFO SampleApp: Playback volume changed: 75 Jun 02 16:00:04 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 02 16:00:04 primo sudo[9438]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 16:00:04 primo volumio[3107]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:04 primo volumio[3107]: info: Shairport-Sync Started Jun 02 16:00:04 primo volumio[3107]: info: Raat Daemon started successfully Jun 02 16:00:04 primo sudo[9459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 02 16:00:04 primo volumio[3107]: info: Executing endpoint restartRAATSocket Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 02 16:00:04 primo sudo[9459]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo sudo[9459]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 02 16:00:04 primo sudo[9463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 02 16:00:04 primo sudo[9463]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:04 primo systemd[1]: Stopping RAAT DAEMON... Jun 02 16:00:04 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 02 16:00:04 primo systemd[1]: raat-daemon.service: Succeeded. Jun 02 16:00:04 primo systemd[1]: Stopped RAAT DAEMON. Jun 02 16:00:04 primo systemd[1]: Started RAAT DAEMON. Jun 02 16:00:04 primo sudo[9463]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:04 primo volumio[3107]: info: Raat Daemon started successfully Jun 02 16:00:04 primo kernel: aml_spdif_open Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: spdif_a keep clk continuous Jun 02 16:00:04 primo kernel: aml_spdif_close Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: aml_spdif_open Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo kernel: spdif_a keep clk continuous Jun 02 16:00:04 primo kernel: aml_spdif_close Jun 02 16:00:04 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:04 primo volumio[3107]: info: Executing endpoint restartRAATSocket Jun 02 16:00:04 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 02 16:00:04 primo volumio[3107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:05 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:05 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:05 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:05 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:05 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:05 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:05 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:05 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:05 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:05 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:05 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:05 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:05 primo volumio[3107]: info: MCU Signalled Playback Inactive Jun 02 16:00:06 primo mpd[9416]: Jun 02 16:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 16:00:06 primo systemd[1]: Started Music Player Daemon. Jun 02 16:00:06 primo sudo[9390]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:06 primo sudo[9341]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:06 primo volumio[3107]: error: MPD error: The expression evaluated to a falsy value: Jun 02 16:00:06 primo volumio[3107]: assert.ok(self.idling) Jun 02 16:00:06 primo volumio[3107]: error: The expression evaluated to a falsy value: Jun 02 16:00:06 primo volumio[3107]: assert.ok(self.idling) Jun 02 16:00:06 primo volumio[3107]: error: updateQueue error: null Jun 02 16:00:07 primo volumio[3107]: info: RAAT: Requesting Headphone Status Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 02 16:00:07 primo volumio[3107]: info: MCU Signalled Headphone Mode Disabled Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 02 16:00:07 primo volumio[3107]: info: Reporting Headphone State: false Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:07 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:07 primo go-librespot[3628]: time="2025-06-02T16:00:07+02:00" level=trace msg="received accesspoint ping" Jun 02 16:00:07 primo go-librespot[3628]: time="2025-06-02T16:00:07+02:00" level=trace msg="received accesspoint pong ack" Jun 02 16:00:07 primo volumio[3107]: info: RAAT: Requesting Headphone Status Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:07 primo volumio[3107]: info: MCU Signalled Headphone Mode Disabled Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 02 16:00:07 primo volumio[3107]: info: Reporting Headphone State: false Jun 02 16:00:07 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:07 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:07 primo go-librespot[3628]: time="2025-06-02T16:00:07+02:00" level=trace msg="sent dealer ping" Jun 02 16:00:07 primo go-librespot[3628]: time="2025-06-02T16:00:07+02:00" level=trace msg="received dealer pong" Jun 02 16:00:11 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:12 primo volumio[3107]: info: Disabling MyMusic plugin upnp Jun 02 16:00:12 primo sudo[9503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 02 16:00:12 primo sudo[9503]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:15 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:19 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:20 primo volumio[3107]: info: [VRCP] preparing for control Jun 02 16:00:20 primo volumio[3107]: info: RAAT Acquiring Control Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::volumioStop Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::stop Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::serviceStop Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::serviceStop Jun 02 16:00:20 primo volumio[3107]: info: RAAT Control Acquired Jun 02 16:00:20 primo volumio[3107]: info: [VRCP] prepared for control Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 16:00:20 primo volumio[3107]: info: [VRCP] acknowledged control Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:20 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:20 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:20 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:20 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:20 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:20 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:20 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:20 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:20 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:20 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:21 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:21 primo kernel: aml_spdif_open Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:21 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:21 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:21 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:21 primo kernel: set normal 512 fs /4 fs Jun 02 16:00:21 primo kernel: set spdifout clk:12288000, mpll:49152000 Jun 02 16:00:21 primo kernel: get spdifout clk:12287994, mpll:49151974 Jun 02 16:00:21 primo kernel: aml_dai_set_spdif_fmt , fmt 0x4000 Jun 02 16:00:21 primo kernel: set normal 512 fs /4 fs Jun 02 16:00:21 primo kernel: set spdifout clk:12288000, mpll:49152000 Jun 02 16:00:21 primo kernel: get spdifout clk:12287994, mpll:49151974 Jun 02 16:00:21 primo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jun 02 16:00:21 primo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 02 16:00:21 primo kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jun 02 16:00:21 primo kernel: aml_spdif_fifo_ctrl, bit depth:24, frddr type:4, toddr:type:4 Jun 02 16:00:21 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback enable Jun 02 16:00:21 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:21 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:21 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:21 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:21 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:21 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:21 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:21 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:21 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:21 primo volumio[3107]: info: MCU Signalled Playback Active Jun 02 16:00:23 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:24 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:24 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:24 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:24 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:24 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:24 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:24 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:24 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:24 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:24 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:27 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:27 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:27 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:27 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:27 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:27 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:27 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:27 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:27 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:27 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:27 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:30 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:30 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:30 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:30 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:30 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:30 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:30 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:30 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:30 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:30 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:31 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:34 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:34 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:34 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:34 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:34 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:34 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:34 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:34 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:34 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:34 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:36 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:37 primo go-librespot[3628]: time="2025-06-02T16:00:37+02:00" level=trace msg="sent dealer ping" Jun 02 16:00:37 primo go-librespot[3628]: time="2025-06-02T16:00:37+02:00" level=trace msg="received dealer pong" Jun 02 16:00:38 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:38 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:38 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:38 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:38 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:38 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:38 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:38 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:38 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:38 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:40 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:41 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:41 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:41 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:41 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:41 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:41 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:41 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:41 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:41 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:41 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:43 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:44 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:44 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:44 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:44 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:44 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:44 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:44 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:44 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:44 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:44 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:47 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:47 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:47 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:47 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:47 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:47 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:47 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:47 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:47 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:47 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:47 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:50 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:50 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:50 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:50 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:50 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:50 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:50 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:50 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:50 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:50 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:51 primo volumio[3107]: info: Enabling MyMusic plugin metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Enabling plugin metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Loading plugin "metavolumio"... Jun 02 16:00:51 primo volumio[3107]: info: PLUGIN START: metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Adding METAVOLUMIO REST API Endpoints Jun 02 16:00:51 primo volumio[3107]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jun 02 16:00:51 primo volumio[3107]: info: Done. Jun 02 16:00:51 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:53 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:53 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:53 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:53 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:53 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:53 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:53 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:53 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:53 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:53 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:54 primo volumio[3107]: info: Enabling MyMusic plugin multiroom Jun 02 16:00:54 primo volumio[3107]: info: Enabling plugin multiroom Jun 02 16:00:54 primo volumio[3107]: info: Loading plugin "multiroom"... Jun 02 16:00:54 primo volumio[3107]: info: Applying required configuration parameters for plugin multiroom Jun 02 16:00:54 primo sudo[9623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 02 16:00:54 primo sudo[9623]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo sudo[9623]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo volumio[3107]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Jun 02 16:00:54 primo volumio[3107]: info: MRS: MultiRoom plugin initialized Jun 02 16:00:54 primo volumio[3107]: info: MRS: STOPPING SNAPCLIENT Jun 02 16:00:54 primo volumio[3107]: info: MRS: Snap server stop Jun 02 16:00:54 primo sudo[9639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 02 16:00:54 primo sudo[9639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo volumio[3107]: info: MRS: STOPPING volumioStreaming Jun 02 16:00:54 primo sudo[9642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 02 16:00:54 primo sudo[9642]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo sudo[9639]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo sudo[9645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 02 16:00:54 primo sudo[9645]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo sudo[9642]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo volumio[3107]: info: Preparing to generate the ALSA configuration file Jun 02 16:00:54 primo sudo[9645]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo volumio[3107]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 02 16:00:54 primo volumio[3107]: info: Reading ALSA contributions from plugins. Jun 02 16:00:54 primo sudo[9649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 02 16:00:54 primo sudo[9649]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo volumio[3107]: info: MRS: volumioStreaming STOPPED Jun 02 16:00:54 primo volumio[3107]: info: MRS: SNAPSERVER STOPPED Jun 02 16:00:54 primo volumio[3107]: info: MRS: SNAPCLIENT STOPPED Jun 02 16:00:54 primo volumio[3107]: info: Asound.conf file written Jun 02 16:00:54 primo sudo[9649]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo sudo[9653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 02 16:00:54 primo sudo[9653]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo sudo[9653]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo kernel: Fine tdm clk setting range (0~2000000), 11289593 Jun 02 16:00:54 primo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Jun 02 16:00:54 primo kernel: out of value, fixed it Jun 02 16:00:54 primo kernel: id=0 set inskew=0 Jun 02 16:00:54 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 02 16:00:54 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 02 16:00:54 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 02 16:00:54 primo volumio[3107]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 02 16:00:54 primo volumio[3107]: info: Output device has changed, restarting MPD Jun 02 16:00:54 primo sudo[9658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 16:00:54 primo sudo[9658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo sudo[9658]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:54 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:54 primo volumio[3107]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:54 primo volumio[3107]: info: Output device has changed, restarting Shairport Sync Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:54 primo sudo[9661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 16:00:54 primo sudo[9661]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo systemd[1]: Stopping Music Player Daemon... Jun 02 16:00:54 primo volumio[3107]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 16:00:54 primo volumio[3107]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:54 primo systemd[1]: mpd.service: Succeeded. Jun 02 16:00:54 primo volumio[3107]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:54 primo systemd[1]: Stopped Music Player Daemon. Jun 02 16:00:54 primo systemd[1]: Starting Music Player Daemon... Jun 02 16:00:54 primo volumio[3107]: info: PLUGIN START: multiroom Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output for this device Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:54 primo volumio[3107]: info: Adding audio output: Jun 02 16:00:54 primo volumio[3107]: error: Can't add: Jun 02 16:00:54 primo volumio[3107]: info: Adding audio output: Jun 02 16:00:54 primo volumio[3107]: error: Can't add: Jun 02 16:00:54 primo volumio[3107]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:54 primo volumio[3107]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 02 16:00:54 primo volumio[3107]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 02 16:00:54 primo volumio[3107]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 02 16:00:54 primo volumio[3107]: info: MPD Permissions set Jun 02 16:00:54 primo volumio[3107]: info: MRS: Removed streaming files Jun 02 16:00:54 primo sudo[9686]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 02 16:00:54 primo sudo[9686]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:54 primo sudo[9668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:54 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:54 primo sudo[9668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:54 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:54 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:54 primo sudo[9668]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:55 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:55 primo sudo[9686]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:55 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:55 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:55 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:55 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:55 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:55 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:55 primo sudo[9689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 02 16:00:55 primo sudo[9689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:55 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:55 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 02 16:00:55 primo qobuz-connect[9418]: 20250602 16:00:55.062 [9418.9418] INFO SampleApp: Stopping Local configuration server Jun 02 16:00:55 primo qobuz-connect[9418]: 20250602 16:00:55.073 [9418.9418] INFO SampleApp: shat down connection on UNIX socket Jun 02 16:00:55 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 02 16:00:55 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 02 16:00:55 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 02 16:00:55 primo sudo[9689]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: Done. Jun 02 16:00:55 primo volumio[3107]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 02 16:00:55 primo volumio[3107]: info: QobuzConnect: setDeactiveState invoked Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:55 primo volumio[3107]: info: Starting Shairport Sync Jun 02 16:00:55 primo volumio[3107]: info: Executing endpoint qc_getconfig Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.198 [9696.9696] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.204 [9696.9696] INFO VolumeManager: [0xab768800]: Setting new playback volume: 75 Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.204 [9696.9696] INFO VolumeManager: [0xab768800]: Setting new mute state: 0 Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.204 [9696.9696] INFO QobuzConnect: [0xab7691d0]: Client initialized! Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.204 [9696.9696] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 02 16:00:55 primo sudo[9701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 02 16:00:55 primo sudo[9701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.221 [9696.9696] INFO LocalConfigManager: [0xab7680e0]: Starting Local Configuration server Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.222 [9696.9696] INFO SampleApp: Starting Local configuration server Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.222 [9696.9696] INFO SampleApp: Connected to UNIX socket client 0xab75ded8 Jun 02 16:00:55 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 02 16:00:55 primo systemd[1]: shairport-sync.service: Succeeded. Jun 02 16:00:55 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 16:00:55 primo volumio[3107]: info: Not Reporting Auto name since its the default one Jun 02 16:00:55 primo volumio[3107]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 02 16:00:55 primo volumio[3107]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 02 16:00:55 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 02 16:00:55 primo sudo[9701]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo qobuz-connect[9696]: 20250602 16:00:55.347 [9696.9696] INFO SampleApp: Playback volume changed: 75 Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:55 primo volumio[3107]: info: Shairport-Sync Started Jun 02 16:00:55 primo sudo[9717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 02 16:00:55 primo sudo[9717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:55 primo sudo[9717]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo sudo[9719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 02 16:00:55 primo sudo[9719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:00:55 primo systemd[1]: Stopping RAAT DAEMON... Jun 02 16:00:55 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 02 16:00:55 primo systemd[1]: raat-daemon.service: Succeeded. Jun 02 16:00:55 primo kernel: asoc-aml-card auge_sound: S/PDIF Playback disable Jun 02 16:00:55 primo kernel: spdif_a keep clk continuous Jun 02 16:00:55 primo kernel: aml_spdif_close Jun 02 16:00:55 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:55 primo systemd[1]: Stopped RAAT DAEMON. Jun 02 16:00:55 primo systemd[1]: Started RAAT DAEMON. Jun 02 16:00:55 primo sudo[9719]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:55 primo volumio[3107]: info: Raat Daemon started successfully Jun 02 16:00:55 primo kernel: aml_spdif_open Jun 02 16:00:55 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:55 primo kernel: spdif_a keep clk continuous Jun 02 16:00:55 primo kernel: aml_spdif_close Jun 02 16:00:55 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:55 primo kernel: aml_spdif_open Jun 02 16:00:55 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:spdif@0 Jun 02 16:00:55 primo kernel: spdif_a keep clk continuous Jun 02 16:00:55 primo kernel: aml_spdif_close Jun 02 16:00:55 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:spdif@0 Jun 02 16:00:55 primo volumio[3107]: info: Executing endpoint restartRAATSocket Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 02 16:00:55 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:55 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:55 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:55 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:55 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:55 primo volumio[3107]: error: MPD error: Error: This socket has been ended by the other party Jun 02 16:00:55 primo volumio[3107]: error: This socket has been ended by the other party {"code":"EPIPE"} Jun 02 16:00:55 primo volumio[3107]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:56 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::servicePushState Jun 02 16:00:56 primo volumio[3107]: info: CoreStateMachine::pushState Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::volumioPushState Jun 02 16:00:56 primo volumio[3107]: info: CoreCommandRouter::volumioGetState Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output update for this device Jun 02 16:00:56 primo volumio[3107]: info: MRS: Pushing multiroomSync output Jun 02 16:00:56 primo volumio[3107]: info: Signalling Playback active due to playback status change Jun 02 16:00:56 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:56 primo volumio[3107]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 02 16:00:56 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:56 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:56 primo volumio[3107]: info: MCU Signalled Playback Inactive Jun 02 16:00:56 primo mpd[9690]: Jun 02 16:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 16:00:56 primo systemd[1]: Started Music Player Daemon. Jun 02 16:00:56 primo sudo[9661]: pam_unix(sudo:session): session closed for user root Jun 02 16:00:56 primo volumio[3107]: error: updateQueue error: null Jun 02 16:00:58 primo volumio[3107]: info: RAAT: Requesting Headphone Status Jun 02 16:00:58 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 02 16:00:58 primo volumio[3107]: info: MCU Signalled Headphone Mode Disabled Jun 02 16:00:58 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 02 16:00:58 primo volumio[3107]: info: Reporting Headphone State: false Jun 02 16:00:58 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:58 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 16:00:58 primo volumio[3107]: info: Updating RAAT Signal Path Jun 02 16:00:59 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 02 16:00:59 primo volumio[3107]: info: MRS: Getting audio outputs on start Jun 02 16:00:59 primo volumio[3107]: info: MRS: Requesting all other devices output Jun 02 16:01:01 primo volumio[3107]: info: Disabling MyMusic plugin upnp Jun 02 16:01:01 primo sudo[9756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jun 02 16:01:01 primo sudo[9756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 02 16:01:02 primo volumio[3107]: info: Enabling MyMusic plugin upnp Jun 02 16:01:02 primo volumio[3107]: info: Enabling plugin upnp Jun 02 16:01:02 primo volumio[3107]: info: Loading plugin "upnp"... Jun 02 16:01:02 primo volumio[3107]: info: [1748872862632] Starting Upmpd Daemon Jun 02 16:01:02 primo volumio[3107]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 02 16:01:02 primo volumio[3107]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:01:02 primo volumio[3107]: Error: listen EADDRINUSE: address already in use :::6599 Jun 02 16:01:02 primo volumio[3107]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Jun 02 16:01:02 primo volumio[3107]: at listenInCluster (net.js:1379:12) Jun 02 16:01:02 primo volumio[3107]: at Server.listen (net.js:1465:7) Jun 02 16:01:02 primo volumio[3107]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jun 02 16:01:02 primo volumio[3107]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jun 02 16:01:02 primo volumio[3107]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jun 02 16:01:02 primo volumio[3107]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 02 16:01:02 primo volumio[3107]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Jun 02 16:01:02 primo volumio[3107]: code: 'EADDRINUSE', Jun 02 16:01:02 primo volumio[3107]: errno: -98, Jun 02 16:01:02 primo volumio[3107]: syscall: 'listen', Jun 02 16:01:02 primo volumio[3107]: address: '::', Jun 02 16:01:02 primo volumio[3107]: port: 6599 Jun 02 16:01:02 primo volumio[3107]: } Jun 02 16:01:02 primo volumio[3107]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 16:01:03 primo sudo[9767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-02 16:00 Jun 02 16:01:03 primo sudo[9767]: 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"