-- Logs begin at Fri 2025-06-06 16:15:04 UTC, end at Tue 2025-06-10 13:57:44 UTC. -- Jun 10 13:56:00 primo volumio[3547]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jun 10 13:56:00 primo volumio[3547]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-26856pyj/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jun 10 13:56:00 primo volumio[3547]: info: Successfully built camilladsp Jun 10 13:56:09 primo volumio[3547]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jun 10 13:56:09 primo volumio[3547]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-wrx0_y2r Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jun 10 13:56:12 primo volumio[3547]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jun 10 13:56:13 primo volumio[3547]: info: Building wheels for collected packages: camilladsp-plot Jun 10 13:56:13 primo volumio[3547]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jun 10 13:56:15 primo volumio[3547]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jun 10 13:56:15 primo volumio[3547]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-subo2x10/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jun 10 13:56:15 primo volumio[3547]: info: Successfully built camilladsp-plot Jun 10 13:56:15 primo volumio[3547]: info: copying hw detection script Jun 10 13:56:15 primo volumio[3547]: info: Detected cpu architecture as aarch64 Jun 10 13:56:17 primo volumio[3547]: info: camilladsp Jun 10 13:56:18 primo sudo[5468]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Jun 10 13:56:18 primo sudo[5468]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:56:18 primo sudo[5468]: pam_unix(sudo:session): session closed for user root Jun 10 13:56:18 primo sudo[5470]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jun 10 13:56:18 primo sudo[5470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:56:18 primo sudo[5470]: pam_unix(sudo:session): session closed for user root Jun 10 13:56:18 primo sudo[5472]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jun 10 13:56:18 primo sudo[5472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:56:18 primo volumio[3547]: info: Reading package lists... Jun 10 13:56:19 primo volumio[3547]: info: Building dependency tree... Jun 10 13:56:19 primo volumio[3547]: info: Reading state information... Jun 10 13:56:21 primo volumio[3547]: info: drc is already the newest version (3.2.2~dfsg0-2). Jun 10 13:56:21 primo sudo[5472]: pam_unix(sudo:session): session closed for user root Jun 10 13:56:21 primo sudo[4994]: pam_unix(sudo:session): session closed for user root Jun 10 13:56:21 primo volumio[3547]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jun 10 13:56:21 primo volumio[3547]: info: Install script completed Jun 10 13:56:21 primo volumio[3547]: info: Adding reference to registry Jun 10 13:56:21 primo volumio[3547]: info: Done installing plugin. Jun 10 13:56:21 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 10 13:56:21 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 10 13:56:21 primo volumio[3547]: info: Folder /tmp/plugins removed Jun 10 13:56:21 primo volumio[3547]: info: Folder /tmp/downloaded_plugin.zip removed Jun 10 13:56:21 primo volumio[3547]: Plugin install end detected on script Jun 10 13:56:21 primo volumio[3547]: info: Folder /data/temp removed Jun 10 13:57:01 primo volumio[3547]: BT PLUGIN MESSAGE: Duration is 476466 Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:01 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:01 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:01 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:01 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:01 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:01 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:01 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:01 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:01 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:01 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:01 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:01 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:01 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:02 primo volumio[3547]: BT PLUGIN MESSAGE: Duration is 223666 Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:02 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:02 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:02 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:02 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:02 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:02 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:02 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:02 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:02 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:02 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:02 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:02 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:02 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:26 primo volumio[3547]: info: Enabling plugin fusiondsp Jun 10 13:57:26 primo volumio[3547]: info: Loading plugin "fusiondsp"... Jun 10 13:57:27 primo volumio[3547]: info: Preparing to generate the ALSA configuration file Jun 10 13:57:27 primo volumio[3547]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 13:57:27 primo volumio[3547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 13:57:27 primo volumio[3547]: info: Reading ALSA contributions from plugins. Jun 10 13:57:27 primo volumio[3547]: info: Asound.conf file written Jun 10 13:57:27 primo sudo[5650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 10 13:57:27 primo sudo[5650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo sudo[5650]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo kernel: Fine tdm clk setting range (0~2000000), 11289593 Jun 10 13:57:27 primo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Jun 10 13:57:27 primo kernel: out of value, fixed it Jun 10 13:57:27 primo kernel: id=0 set inskew=0 Jun 10 13:57:27 primo volumio[3547]: No protocol specified Jun 10 13:57:27 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:27 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 10 13:57:27 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 10 13:57:27 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 10 13:57:27 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 10 13:57:27 primo volumio[3547]: info: Output device has changed, restarting MPD Jun 10 13:57:27 primo sudo[5655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 13:57:27 primo sudo[5655]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo volumio[3547]: info: Output device has changed, restarting Shairport Sync Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:27 primo sudo[5655]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo sudo[5658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 13:57:27 primo sudo[5658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo systemd[1]: Stopping Music Player Daemon... Jun 10 13:57:27 primo volumio[3547]: No protocol specified Jun 10 13:57:27 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:27 primo volumio[3547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 13:57:27 primo volumio[3547]: No protocol specified Jun 10 13:57:27 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:27 primo volumio[3547]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:27 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:27 primo volumio[3547]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:27 primo vtcs[4301]: [2025-06-10 13:57:27.465] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jun 10 13:57:27 primo vtcs[4301]: [2025-06-10 13:57:27.470] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Jun 10 13:57:27 primo vtcs[4301]: [2025-06-10 13:57:27.470] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Jun 10 13:57:27 primo systemd[1]: mpd.service: Succeeded. Jun 10 13:57:27 primo systemd[1]: Stopped Music Player Daemon. Jun 10 13:57:27 primo volumio[3547]: info: PLUGIN START: fusiondsp Jun 10 13:57:27 primo systemd[1]: Starting Music Player Daemon... Jun 10 13:57:27 primo volumio[3547]: info: Loading i18n strings for locale it Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 10 13:57:27 primo sudo[5667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:27 primo volumio[3547]: info: FusionDsp - mixtype--------------------- Hardware Jun 10 13:57:27 primo volumio[3547]: info: Preparing to generate the ALSA configuration file Jun 10 13:57:27 primo sudo[5667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 10 13:57:27 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:27 primo volumio[3547]: info: Done. Jun 10 13:57:27 primo systemd[1]: vtcs.service: Succeeded. Jun 10 13:57:27 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 10 13:57:27 primo sudo[5667]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo sudo[5680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:27 primo sudo[5680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo sudo[5668]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 13:57:27 primo sudo[5668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo volumio[3547]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 13:57:27 primo volumio[3547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 13:57:27 primo volumio[3547]: info: Reading ALSA contributions from plugins. Jun 10 13:57:27 primo sudo[5668]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:27 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:27 primo sudo[5680]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:27 primo sudo[5689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 13:57:27 primo sudo[5689]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:27 primo sudo[5689]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 13:57:27 primo volumio[3547]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 10 13:57:27 primo volumio[3547]: info: MPD Permissions set Jun 10 13:57:27 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:27 primo sudo[5693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 13:57:27 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:27 primo sudo[5693]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:27 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:27 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:27 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:27 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:27 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:27 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 10 13:57:27 primo qobuz-connect[4149]: 20250610 13:57:27.873 [4149.4149] INFO SampleApp: Stopping Local configuration server Jun 10 13:57:27 primo qobuz-connect[4149]: 20250610 13:57:27.883 [4149.4149] INFO SampleApp: shat down connection on UNIX socket Jun 10 13:57:27 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 10 13:57:27 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 10 13:57:27 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:27 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 13:57:27 primo sudo[5693]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: Not Reporting Auto name since its the default one Jun 10 13:57:28 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:28 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:28 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:28 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:28 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:28 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:28 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:28 primo volumio[3547]: info: Enabling plugin fusiondsp Jun 10 13:57:28 primo volumio[3547]: info: Loading plugin "fusiondsp"... Jun 10 13:57:28 primo volumio[3547]: info: Applying required configuration parameters for plugin fusiondsp Jun 10 13:57:28 primo volumio[3547]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 10 13:57:28 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:28 primo volumio[3547]: info: Executing endpoint qc_getconfig Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.270 [5695.5695] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.277 [5695.5695] INFO VolumeManager: [0xabdc1220]: Setting new playback volume: 75 Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.277 [5695.5695] INFO VolumeManager: [0xabdc1220]: Setting new mute state: 0 Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.277 [5695.5695] INFO QobuzConnect: [0xabdc1bf0]: Client initialized! Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.277 [5695.5695] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.324 [5695.5695] INFO LocalConfigManager: [0xabdc0b00]: Starting Local Configuration server Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.324 [5695.5695] INFO SampleApp: Starting Local configuration server Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.328 [5695.5695] INFO SampleApp: Connected to UNIX socket client 0xabdb6ed8 Jun 10 13:57:28 primo volumio[3547]: info: FusionDsp - Jun 10 13:57:28 primo qobuz-connect[5695]: 20250610 13:57:28.420 [5695.5695] INFO SampleApp: Playback volume changed: 75 Jun 10 13:57:28 primo volumio[3547]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 13:57:28 primo volumio[3547]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 13:57:28 primo volumio[3547]: info: FusionDsp - undefined Jun 10 13:57:28 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 10 13:57:29 primo volumio[3547]: No protocol specified Jun 10 13:57:29 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:29 primo volumio[3547]: info: Starting Shairport Sync Jun 10 13:57:29 primo sudo[5712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 10 13:57:29 primo sudo[5712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo volumio[3547]: info: Asound.conf file unchanged, so no further update is needed Jun 10 13:57:29 primo volumio[3547]: info: Output device has changed, restarting MPD Jun 10 13:57:29 primo sudo[5712]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 13:57:29 primo sudo[5715]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 10 13:57:29 primo sudo[5716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 13:57:29 primo sudo[5720]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo volumio[3547]: info: Output device has changed, restarting Shairport Sync Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:29 primo systemd[1]: Stopping RAAT DAEMON... Jun 10 13:57:29 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 13:57:29 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:29 primo systemd[1]: raat-daemon.service: Succeeded. Jun 10 13:57:29 primo systemd[1]: Stopped RAAT DAEMON. Jun 10 13:57:29 primo sudo[5720]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo systemd[1]: Started RAAT DAEMON. Jun 10 13:57:29 primo sudo[5716]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 13:57:29 primo sudo[5722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo systemd[1]: shairport-sync.service: Succeeded. Jun 10 13:57:29 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:29 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:29 primo sudo[5715]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo volumio[3547]: No protocol specified Jun 10 13:57:29 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:29 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:29 primo systemd[1]: mpd.service: Succeeded. Jun 10 13:57:29 primo volumio[3547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 13:57:29 primo volumio[3547]: No protocol specified Jun 10 13:57:29 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:29 primo volumio[3547]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:29 primo systemd[1]: Stopped Music Player Daemon. Jun 10 13:57:29 primo systemd[1]: Starting Music Player Daemon... Jun 10 13:57:29 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:29 primo volumio[3547]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:29 primo volumio[3547]: info: Preparing to generate the ALSA configuration file Jun 10 13:57:29 primo sudo[5748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:29 primo sudo[5748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 13:57:29 primo sudo[5740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5748]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5740]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:29 primo sudo[5753]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5753]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 13:57:29 primo sudo[5761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo sudo[5761]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo sudo[5769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 13:57:29 primo sudo[5769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:29 primo volumio[3547]: info: camilladsp spawned new process with pid 5770, instance 1, run: true Jun 10 13:57:29 primo volumio[3547]: info: camilladsp service started and running in background, instance 1 Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:29 primo qobuz-connect[5695]: 20250610 13:57:29.855 [5695.5695] INFO SampleApp: Stopping Local configuration server Jun 10 13:57:29 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 10 13:57:29 primo qobuz-connect[5695]: 20250610 13:57:29.866 [5695.5695] INFO SampleApp: shat down connection on UNIX socket Jun 10 13:57:29 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 10 13:57:29 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 10 13:57:29 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 13:57:29 primo sudo[5769]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:29 primo volumio[3547]: No protocol specified Jun 10 13:57:29 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:29 primo volumio[3547]: cannot open device 'volumioHw': Device or resource busy Jun 10 13:57:29 primo volumio[3547]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 10 13:57:29 primo volumio[3547]: No protocol specified Jun 10 13:57:29 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:29 primo volumio[3547]: cannot open device 'volumioHw': Device or resource busy Jun 10 13:57:29 primo volumio[3547]: info: FusionDsp loaded Jun 10 13:57:29 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 13:57:30 primo sudo[5780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 10 13:57:30 primo sudo[5780]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:30 primo volumio[3547]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 10 13:57:30 primo volumio[3547]: info: Adding Signal Path Element [object Object] Jun 10 13:57:30 primo volumio[3547]: info: Adding fusiondspeq DSP Signal Path Element Jun 10 13:57:30 primo volumio[3547]: info: Additional DSP elements updated Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:30 primo systemd[1]: Started FusionDsp Daemon. Jun 10 13:57:30 primo volumio[3547]: info: FusionDsp - ---- installed callbackRead Jun 10 13:57:30 primo sudo[5780]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:30 primo volumio[3547]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 10 13:57:30 primo volumio[3547]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 13:57:30 primo volumio[3547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 13:57:30 primo volumio[3547]: info: Reading ALSA contributions from plugins. Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:30 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:30 primo volumio[3547]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 10 13:57:30 primo volumio[3547]: info: MPD Permissions set Jun 10 13:57:30 primo volumio[3547]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 10 13:57:30 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:30 primo volumio[3547]: info: Shairport-Sync Started Jun 10 13:57:30 primo volumio[3547]: info: Raat Daemon started successfully Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: Not Reporting Auto name since its the default one Jun 10 13:57:30 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:30 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:30 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:30 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:30 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:30 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:30 primo volumio[3547]: error: FusionDsp - WebSocket error: [object Object] Jun 10 13:57:30 primo volumio[3547]: info: Executing endpoint restartRAATSocket Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 10 13:57:30 primo volumio[3547]: info: Executing endpoint qc_getconfig Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.393 [5776.5776] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.403 [5776.5776] INFO VolumeManager: [0xaaedc220]: Setting new playback volume: 75 Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.403 [5776.5776] INFO VolumeManager: [0xaaedc220]: Setting new mute state: 0 Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.404 [5776.5776] INFO QobuzConnect: [0xaaedcbf0]: Client initialized! Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.404 [5776.5776] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.444 [5776.5776] INFO LocalConfigManager: [0xaaedbb00]: Starting Local Configuration server Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.444 [5776.5776] INFO SampleApp: Starting Local configuration server Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.445 [5776.5776] INFO SampleApp: Connected to UNIX socket client 0xaaed1ed8 Jun 10 13:57:30 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:30 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:30 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:30 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:30 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:30 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:30 primo volumio[3547]: 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 10 13:57:30 primo volumio[3547]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 13:57:30 primo volumio[3547]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo qobuz-connect[5776]: 20250610 13:57:30.543 [5776.5776] INFO SampleApp: Playback volume changed: 75 Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 10 13:57:30 primo volumio[3547]: No protocol specified Jun 10 13:57:30 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:30 primo sudo[5800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 10 13:57:30 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:30 primo sudo[5800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:30 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:30 primo volumio[3547]: 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 10 13:57:30 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:30 primo sudo[5800]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:30 primo sudo[5802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 10 13:57:30 primo sudo[5802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:30 primo volumio[3547]: info: Starting Shairport Sync Jun 10 13:57:30 primo systemd[1]: Stopping RAAT DAEMON... Jun 10 13:57:30 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:30 primo systemd[1]: raat-daemon.service: Succeeded. Jun 10 13:57:30 primo systemd[1]: Stopped RAAT DAEMON. Jun 10 13:57:31 primo systemd[1]: Started RAAT DAEMON. Jun 10 13:57:31 primo sudo[5802]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:31 primo sudo[5809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 13:57:31 primo sudo[5809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:31 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:31 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:31 primo volumio[3547]: info: Raat Daemon started successfully Jun 10 13:57:31 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 13:57:31 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:31 primo systemd[1]: shairport-sync.service: Succeeded. Jun 10 13:57:31 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:31 primo volumio[3547]: info: Executing endpoint restartRAATSocket Jun 10 13:57:31 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 10 13:57:31 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:31 primo volumio[3547]: 0+0 records in Jun 10 13:57:31 primo volumio[3547]: 0+0 records out Jun 10 13:57:31 primo volumio[3547]: 0 bytes copied, 0.000220207 s, 0.0 kB/s Jun 10 13:57:31 primo volumio[3547]: info: camilladsp spawned new process with pid 5830, instance 1, run: true Jun 10 13:57:31 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:31 primo sudo[5809]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:31 primo volumio[3547]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:31 primo volumio[3547]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:31 primo volumio[3547]: info: Asound.conf file written Jun 10 13:57:31 primo sudo[5839]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 10 13:57:31 primo sudo[5839]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:31 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 10 13:57:31 primo sudo[5839]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:31 primo sudo[5842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 10 13:57:31 primo sudo[5842]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:31 primo sudo[5842]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:31 primo kernel: Fine tdm clk setting range (0~2000000), 11289593 Jun 10 13:57:31 primo kernel: Fine spdif sysclk setting range(0~2000000), 5644797 Jun 10 13:57:31 primo kernel: out of value, fixed it Jun 10 13:57:31 primo kernel: id=0 set inskew=0 Jun 10 13:57:31 primo volumio[3547]: No protocol specified Jun 10 13:57:31 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:31 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 10 13:57:31 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 10 13:57:31 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 10 13:57:31 primo volumio[3547]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 10 13:57:31 primo volumio[3547]: info: Output device has changed, restarting MPD Jun 10 13:57:31 primo sudo[5852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 13:57:31 primo sudo[5852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:31 primo volumio[3547]: info: Output device has changed, restarting Shairport Sync Jun 10 13:57:31 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:31 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:31 primo sudo[5852]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:31 primo sudo[5855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 13:57:31 primo sudo[5855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:31 primo volumio[3547]: No protocol specified Jun 10 13:57:31 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:31 primo volumio[3547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 13:57:31 primo volumio[3547]: No protocol specified Jun 10 13:57:31 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:31 primo volumio[3547]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 13:57:31 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:31 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:31 primo systemd[1]: mpd.service: Succeeded. Jun 10 13:57:31 primo systemd[1]: Stopped Music Player Daemon. Jun 10 13:57:32 primo systemd[1]: Starting Music Player Daemon... Jun 10 13:57:32 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:32 primo volumio[3547]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: Preparing to generate the ALSA configuration file Jun 10 13:57:32 primo sudo[5866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:32 primo sudo[5866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:32 primo volumio[3547]: info: PLUGIN START: fusiondsp Jun 10 13:57:32 primo volumio[3547]: info: Loading i18n strings for locale it Jun 10 13:57:32 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 10 13:57:32 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: FusionDsp - mixtype--------------------- Hardware Jun 10 13:57:32 primo volumio[3547]: info: Done. Jun 10 13:57:32 primo systemd[1]: vtcs.service: Succeeded. Jun 10 13:57:32 primo sudo[5862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 13:57:32 primo sudo[5862]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:32 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 10 13:57:32 primo sudo[5866]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:32 primo sudo[5862]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:32 primo sudo[5872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:32 primo sudo[5872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:32 primo sudo[5872]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:32 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:32 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:32 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:32 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:32 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:32 primo sudo[5880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 13:57:32 primo sudo[5880]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:32 primo volumio[3547]: info: Shairport-Sync Started Jun 10 13:57:32 primo volumio[3547]: info: RAAT: Requesting Headphone Status Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 10 13:57:32 primo sudo[5880]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:32 primo sudo[5882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 13:57:32 primo sudo[5882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:32 primo volumio[3547]: info: camilladsp spawned new process with pid 5883, instance 1, run: true Jun 10 13:57:32 primo volumio[3547]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 13:57:32 primo volumio[3547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 13:57:32 primo volumio[3547]: info: Reading ALSA contributions from plugins. Jun 10 13:57:32 primo volumio[3547]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Jun 10 13:57:32 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 10 13:57:32 primo qobuz-connect[5776]: 20250610 13:57:32.548 [5776.5776] INFO SampleApp: Stopping Local configuration server Jun 10 13:57:32 primo qobuz-connect[5776]: 20250610 13:57:32.558 [5776.5776] INFO SampleApp: shat down connection on UNIX socket Jun 10 13:57:32 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 10 13:57:32 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 10 13:57:32 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 13:57:32 primo volumio[3547]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 10 13:57:32 primo sudo[5882]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:32 primo volumio[3547]: info: MPD Permissions set Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: Not Reporting Auto name since its the default one Jun 10 13:57:32 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:32 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:32 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:32 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:32 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:32 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:32 primo volumio[3547]: info: MCU Signalled Headphone Mode Disabled Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 10 13:57:32 primo volumio[3547]: info: Reporting Headphone State: false Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:32 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:32 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:32 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:33 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:33 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:33 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:33 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:33 primo volumio[3547]: info: Executing endpoint tc_getconfig Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 10 13:57:33 primo volumio[3547]: 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 10 13:57:33 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:33 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:33 primo volumio[3547]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 10 13:57:33 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:33 primo volumio[3547]: info: camilladsp spawned new process with pid 5904, instance 1, run: true Jun 10 13:57:33 primo volumio[3547]: info: Executing endpoint qc_getconfig Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.321 [5893.5893] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.336 [5893.5893] INFO VolumeManager: [0xac608220]: Setting new playback volume: 75 Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.336 [5893.5893] INFO VolumeManager: [0xac608220]: Setting new mute state: 0 Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.337 [5893.5893] INFO QobuzConnect: [0xac608bf0]: Client initialized! Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.337 [5893.5893] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.374 [5893.5893] INFO LocalConfigManager: [0xac607b00]: Starting Local Configuration server Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.374 [5893.5893] INFO SampleApp: Starting Local configuration server Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.374 [5893.5893] INFO SampleApp: Connected to UNIX socket client 0xac5fded8 Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo qobuz-connect[5893]: 20250610 13:57:33.470 [5893.5893] INFO SampleApp: Playback volume changed: 75 Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:33 primo volumio[3547]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 13:57:33 primo volumio[3547]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 13:57:33 primo volumio[3547]: info: camilladsp spawned new process with pid 5923, instance 1, run: true Jun 10 13:57:33 primo volumio[3547]: info: FusionDsp - Jun 10 13:57:33 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 10 13:57:34 primo volumio[3547]: No protocol specified Jun 10 13:57:34 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:34 primo sudo[5933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 10 13:57:34 primo sudo[5933]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:34 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:34 primo volumio[3547]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:34 primo volumio[3547]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:34 primo sudo[5933]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:34 primo sudo[5935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 10 13:57:34 primo sudo[5935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:34 primo volumio[3547]: info: RAAT: Requesting Headphone Status Jun 10 13:57:34 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 10 13:57:34 primo systemd[1]: Stopping RAAT DAEMON... Jun 10 13:57:34 primo sudo[5938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 10 13:57:34 primo sudo[5938]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:34 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:34 primo systemd[1]: raat-daemon.service: Succeeded. Jun 10 13:57:34 primo systemd[1]: Stopped RAAT DAEMON. Jun 10 13:57:34 primo systemd[1]: Started RAAT DAEMON. Jun 10 13:57:34 primo sudo[5935]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:34 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 10 13:57:34 primo sudo[5938]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:34 primo volumio[3547]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 10 13:57:34 primo volumio[3547]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jun 10 13:57:34 primo volumio[3547]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 10 13:57:34 primo volumio[3547]: info: camilladsp spawned new process with pid 5959, instance 2, run: true Jun 10 13:57:34 primo volumio[3547]: info: camilladsp service started and running in background, instance 2 Jun 10 13:57:34 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:34 primo volumio[3547]: No protocol specified Jun 10 13:57:34 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:34 primo volumio[3547]: cannot open device 'volumioHw': Device or resource busy Jun 10 13:57:34 primo volumio[3547]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 10 13:57:34 primo volumio[3547]: No protocol specified Jun 10 13:57:34 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:34 primo volumio[3547]: cannot open device 'volumioHw': Device or resource busy Jun 10 13:57:34 primo volumio[3547]: info: FusionDsp loaded Jun 10 13:57:34 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 10 13:57:34 primo volumio[3547]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 10 13:57:34 primo volumio[3547]: info: Adding Signal Path Element [object Object] Jun 10 13:57:34 primo volumio[3547]: info: Updating fusiondspeq DSP Signal Path Element Jun 10 13:57:34 primo volumio[3547]: info: Additional DSP elements updated Jun 10 13:57:34 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:34 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:34 primo sudo[5970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 10 13:57:34 primo volumio[3547]: info: FusionDsp - ---- installed callbackRead Jun 10 13:57:34 primo sudo[5970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:34 primo volumio[3547]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 10 13:57:34 primo volumio[3547]: info: FusionDsp - undefined Jun 10 13:57:34 primo sudo[5970]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:35 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:35 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:35 primo volumio[3547]: info: FusionDsp - ---- read samplerate, raw: Jun 10 13:57:35 primo volumio[3547]: error: FusionDsp - invalid sample rate Jun 10 13:57:35 primo volumio[3547]: info: FusionDsp - ---- read samplerate, raw: Jun 10 13:57:35 primo volumio[3547]: error: FusionDsp - invalid sample rate Jun 10 13:57:35 primo volumio[3547]: info: Raat Daemon started successfully Jun 10 13:57:35 primo volumio[3547]: info: TidalConnect service started! Jun 10 13:57:35 primo volumio[3547]: info: camilladsp spawned new process with pid 5972, instance 2, run: true Jun 10 13:57:35 primo volumio[3547]: info: Starting Shairport Sync Jun 10 13:57:35 primo volumio[3547]: info: MCU Signalled Headphone Mode Disabled Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 10 13:57:35 primo volumio[3547]: info: Reporting Headphone State: false Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:35 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:35 primo sudo[5980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 13:57:35 primo sudo[5980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:35 primo volumio[3547]: error: FusionDsp - WebSocket error: [object Object] Jun 10 13:57:35 primo volumio[3547]: info: Executing endpoint restartRAATSocket Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 10 13:57:35 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 13:57:35 primo systemd[1]: shairport-sync.service: Succeeded. Jun 10 13:57:35 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:35 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:35 primo sudo[5980]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:35 primo volumio[3547]: info: camilladsp spawned new process with pid 5985, instance 1, run: true Jun 10 13:57:35 primo volumio[3547]: 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 10 13:57:35 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:35 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:35 primo volumio[3547]: info: Shairport-Sync Started Jun 10 13:57:35 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:35 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:35 primo volumio[3547]: info: camilladsp spawned new process with pid 6009, instance 2, run: true Jun 10 13:57:35 primo volumio[3547]: info: Asound.conf file unchanged, so no further update is needed Jun 10 13:57:35 primo volumio[3547]: info: Output device has changed, restarting MPD Jun 10 13:57:35 primo sudo[6024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 13:57:35 primo sudo[6024]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:35 primo sudo[6024]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:35 primo volumio[3547]: info: Output device has changed, restarting Shairport Sync Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:35 primo sudo[6028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 13:57:35 primo sudo[6028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:35 primo volumio[3547]: No protocol specified Jun 10 13:57:35 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:35 primo volumio[3547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 13:57:35 primo volumio[3547]: No protocol specified Jun 10 13:57:35 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:35 primo volumio[3547]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:35 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:35 primo systemd[1]: mpd.service: Succeeded. Jun 10 13:57:35 primo systemd[1]: Stopped Music Player Daemon. Jun 10 13:57:35 primo systemd[1]: Starting Music Player Daemon... Jun 10 13:57:35 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:35 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:35 primo volumio[3547]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:36 primo volumio[3547]: info: Preparing to generate the ALSA configuration file Jun 10 13:57:36 primo sudo[6038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:36 primo sudo[6038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:36 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 10 13:57:36 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:36 primo systemd[1]: vtcs.service: Succeeded. Jun 10 13:57:36 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 10 13:57:36 primo sudo[6033]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 13:57:36 primo sudo[6033]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:36 primo sudo[6041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:36 primo sudo[6041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:36 primo sudo[6038]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:36 primo sudo[6033]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:36 primo sudo[6041]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:36 primo volumio[3547]: info: Executing endpoint tc_getconfig Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 10 13:57:36 primo sudo[6051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 13:57:36 primo sudo[6051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:36 primo sudo[6051]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:36 primo volumio[3547]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 13:57:36 primo sudo[6054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 13:57:36 primo sudo[6054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:36 primo volumio[3547]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 10 13:57:36 primo volumio[3547]: info: Reading ALSA contributions from plugins. Jun 10 13:57:36 primo volumio[3547]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 10 13:57:36 primo volumio[3547]: info: MPD Permissions set Jun 10 13:57:36 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 10 13:57:36 primo volumio[3547]: info: camilladsp spawned new process with pid 6059, instance 1, run: true Jun 10 13:57:36 primo qobuz-connect[5893]: 20250610 13:57:36.614 [5893.5893] INFO SampleApp: Stopping Local configuration server Jun 10 13:57:36 primo qobuz-connect[5893]: 20250610 13:57:36.624 [5893.5893] INFO SampleApp: shat down connection on UNIX socket Jun 10 13:57:36 primo volumio[3547]: info: camilladsp spawned new process with pid 6066, instance 2, run: true Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 13:57:36 primo volumio[3547]: info: Not Reporting Auto name since its the default one Jun 10 13:57:36 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:36 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 10 13:57:36 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:36 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:36 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:36 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 13:57:36 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:36 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:36 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:36 primo sudo[6054]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:36 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:36 primo volumio[3547]: 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 10 13:57:36 primo volumio[3547]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 10 13:57:36 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:36 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:36 primo volumio[3547]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:36 primo volumio[3547]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:37 primo volumio[3547]: info: camilladsp spawned new process with pid 6081, instance 2, run: true Jun 10 13:57:37 primo sudo[6080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 10 13:57:37 primo sudo[6080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:37 primo volumio[3547]: info: camilladsp spawned new process with pid 6088, instance 1, run: true Jun 10 13:57:37 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:37 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 10 13:57:37 primo sudo[6080]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:37 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:37 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:37 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:37 primo volumio[3547]: info: RAAT: Requesting Headphone Status Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: Executing endpoint qc_getconfig Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.470 [6077.6077] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 13:57:37 primo volumio[3547]: info: camilladsp spawned new process with pid 6105, instance 1, run: true Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.572 [6077.6077] INFO VolumeManager: [0xab7c6220]: Setting new playback volume: 75 Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.572 [6077.6077] INFO VolumeManager: [0xab7c6220]: Setting new mute state: 0 Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.572 [6077.6077] INFO QobuzConnect: [0xab7c6bf0]: Client initialized! Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.572 [6077.6077] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.598 [6077.6077] INFO LocalConfigManager: [0xab7c5b00]: Starting Local Configuration server Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.598 [6077.6077] INFO SampleApp: Starting Local configuration server Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.599 [6077.6077] INFO SampleApp: Connected to UNIX socket client 0xab7bbed8 Jun 10 13:57:37 primo qobuz-connect[6077]: 20250610 13:57:37.620 [6077.6077] INFO SampleApp: Playback volume changed: 75 Jun 10 13:57:37 primo volumio[3547]: info: camilladsp spawned new process with pid 6113, instance 2, run: true Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 10 13:57:37 primo volumio[3547]: No protocol specified Jun 10 13:57:37 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:37 primo volumio[3547]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 13:57:37 primo volumio[3547]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 13:57:37 primo volumio[3547]: info: TidalConnect service started! Jun 10 13:57:37 primo sudo[6125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 10 13:57:37 primo sudo[6125]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:37 primo volumio[3547]: info: MCU Signalled Headphone Mode Disabled Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 10 13:57:37 primo volumio[3547]: info: Reporting Headphone State: false Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:37 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:37 primo volumio[3547]: info: Executing endpoint tc_getconfig Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 10 13:57:37 primo sudo[6125]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:37 primo vtcs[6096]: STARTING TidalConnect services, version: 1.4.0.34 Jun 10 13:57:37 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:38 primo sudo[6127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 10 13:57:38 primo sudo[6127]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:38 primo volumio[3547]: info: camilladsp spawned new process with pid 6129, instance 2, run: true Jun 10 13:57:38 primo volumio[3547]: info: Starting Shairport Sync Jun 10 13:57:38 primo systemd[1]: Stopping RAAT DAEMON... Jun 10 13:57:38 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:38 primo systemd[1]: raat-daemon.service: Succeeded. Jun 10 13:57:38 primo systemd[1]: Stopped RAAT DAEMON. Jun 10 13:57:38 primo sudo[6137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 13:57:38 primo sudo[6137]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:38 primo volumio[3547]: info: camilladsp spawned new process with pid 6138, instance 1, run: true Jun 10 13:57:38 primo systemd[1]: Started RAAT DAEMON. Jun 10 13:57:38 primo sudo[6127]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:38 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:38 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:38 primo vtcs[6096]: STARTED TidalConnect services. Jun 10 13:57:38 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 13:57:38 primo systemd[1]: shairport-sync.service: Succeeded. Jun 10 13:57:38 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:38 primo volumio[3547]: info: camilladsp spawned new process with pid 6164, instance 2, run: true Jun 10 13:57:38 primo volumio[3547]: info: Asound.conf file unchanged, so no further update is needed Jun 10 13:57:38 primo volumio[3547]: info: Output device has changed, restarting MPD Jun 10 13:57:38 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:38 primo sudo[6137]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:38 primo sudo[6175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 13:57:38 primo sudo[6175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:38 primo volumio[3547]: info: Output device has changed, restarting Shairport Sync Jun 10 13:57:38 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:38 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:38 primo sudo[6175]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:38 primo sudo[6180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 13:57:38 primo sudo[6180]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:38 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:38 primo systemd[1]: mpd.service: Succeeded. Jun 10 13:57:38 primo systemd[1]: Stopped Music Player Daemon. Jun 10 13:57:38 primo volumio[3547]: No protocol specified Jun 10 13:57:38 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:38 primo volumio[3547]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 10 13:57:38 primo volumio[3547]: No protocol specified Jun 10 13:57:38 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:38 primo volumio[3547]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jun 10 13:57:38 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:38 primo systemd[1]: Starting Music Player Daemon... Jun 10 13:57:38 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:38 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:38 primo volumio[3547]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Jun 10 13:57:38 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:38 primo sudo[6190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:38 primo sudo[6190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:38 primo systemd[1]: Stopping Volumio Tidal Connect Service... Jun 10 13:57:38 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:38 primo systemd[1]: vtcs.service: Succeeded. Jun 10 13:57:38 primo systemd[1]: Stopped Volumio Tidal Connect Service. Jun 10 13:57:38 primo sudo[6194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jun 10 13:57:38 primo sudo[6194]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:39 primo sudo[6190]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:39 primo sudo[6186]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 13:57:39 primo sudo[6186]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:39 primo sudo[6194]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:39 primo sudo[6186]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:39 primo sudo[6202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 10 13:57:39 primo sudo[6202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:39 primo volumio[3547]: info: Raat Daemon started successfully Jun 10 13:57:39 primo sudo[6202]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:39 primo volumio[3547]: info: camilladsp spawned new process with pid 6206, instance 1, run: true Jun 10 13:57:39 primo sudo[6207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 10 13:57:39 primo volumio[3547]: info: Executing endpoint tc_connect Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 10 13:57:39 primo volumio[3547]: info: Connecting to TidalConnect Jun 10 13:57:39 primo sudo[6207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:39 primo volumio[3547]: info: Executing endpoint restartRAATSocket Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 10 13:57:39 primo volumio[3547]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Jun 10 13:57:39 primo volumio[3547]: info: MPD Permissions set Jun 10 13:57:39 primo volumio[3547]: info: Shairport-Sync Started Jun 10 13:57:39 primo qobuz-connect[6077]: 20250610 13:57:39.331 [6077.6077] INFO SampleApp: Stopping Local configuration server Jun 10 13:57:39 primo qobuz-connect[6077]: 20250610 13:57:39.339 [6077.6077] INFO SampleApp: shat down connection on UNIX socket Jun 10 13:57:39 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 10 13:57:39 primo volumio[3547]: info: camilladsp spawned new process with pid 6216, instance 2, run: true Jun 10 13:57:39 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:39 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:39 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:39 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 10 13:57:39 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 10 13:57:39 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 10 13:57:39 primo sudo[6207]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:39 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:39 primo volumio[3547]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 10 13:57:39 primo volumio[3547]: info: QobuzConnect: setDeactiveState invoked Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:39 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:39 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: Not Reporting Auto name since its the default one Jun 10 13:57:39 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:39 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:39 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:39 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:39 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:39 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:39 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:39 primo volumio[3547]: info: camilladsp spawned new process with pid 6237, instance 1, run: true Jun 10 13:57:39 primo volumio[3547]: info: camilladsp spawned new process with pid 6244, instance 2, run: true Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 10 13:57:39 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:40 primo volumio[3547]: info: Executing endpoint qc_getconfig Jun 10 13:57:40 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.012 [6223.6223] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.020 [6223.6223] INFO VolumeManager: [0xac981220]: Setting new playback volume: 75 Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.020 [6223.6223] INFO VolumeManager: [0xac981220]: Setting new mute state: 0 Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.020 [6223.6223] INFO QobuzConnect: [0xac981bf0]: Client initialized! Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.020 [6223.6223] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 10 13:57:40 primo volumio[3547]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:40 primo volumio[3547]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.082 [6223.6223] INFO LocalConfigManager: [0xac980b00]: Starting Local Configuration server Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.082 [6223.6223] INFO SampleApp: Starting Local configuration server Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.083 [6223.6223] INFO SampleApp: Connected to UNIX socket client 0xac976ed8 Jun 10 13:57:40 primo volumio[3547]: 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 10 13:57:40 primo volumio[3547]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 10 13:57:40 primo volumio[3547]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 10 13:57:40 primo sudo[6259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 10 13:57:40 primo sudo[6259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:40 primo qobuz-connect[6223]: 20250610 13:57:40.162 [6223.6223] INFO SampleApp: Playback volume changed: 75 Jun 10 13:57:40 primo volumio[3547]: info: camilladsp spawned new process with pid 6262, instance 2, run: true Jun 10 13:57:40 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Jun 10 13:57:40 primo systemd[1]: Started Volumio Tidal Connect Service. Jun 10 13:57:40 primo sudo[6259]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:40 primo volumio[3547]: No protocol specified Jun 10 13:57:40 primo volumio[3547]: xcb_connection_has_error() returned true Jun 10 13:57:40 primo sudo[6276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Jun 10 13:57:40 primo sudo[6276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:40 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:40 primo volumio[3547]: info: TidalConnect service started! Jun 10 13:57:40 primo sudo[6276]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:40 primo volumio[3547]: info: camilladsp spawned new process with pid 6278, instance 1, run: true Jun 10 13:57:40 primo volumio[3547]: info: Starting Shairport Sync Jun 10 13:57:40 primo sudo[6279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Jun 10 13:57:40 primo sudo[6279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:40 primo systemd[1]: Stopping RAAT DAEMON... Jun 10 13:57:40 primo sudo[6288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 10 13:57:40 primo sudo[6288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:40 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Jun 10 13:57:40 primo systemd[1]: raat-daemon.service: Succeeded. Jun 10 13:57:40 primo systemd[1]: Stopped RAAT DAEMON. Jun 10 13:57:40 primo volumio[3547]: info: camilladsp spawned new process with pid 6290, instance 2, run: true Jun 10 13:57:40 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:40 primo systemd[1]: Started RAAT DAEMON. Jun 10 13:57:40 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:40 primo sudo[6279]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:40 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 10 13:57:40 primo volumio[3547]: info: Raat Daemon started successfully Jun 10 13:57:40 primo volumio[3547]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Jun 10 13:57:40 primo volumio[3547]: info: FusionDsp - Stopping FusionDsp service Jun 10 13:57:40 primo volumio[3547]: info: camilladsp stopping service pid 6290... Jun 10 13:57:40 primo systemd[1]: shairport-sync.service: Succeeded. Jun 10 13:57:40 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:40 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 10 13:57:40 primo sudo[6288]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:40 primo volumio[3547]: info: camilladsp service terminated, instance 2 Jun 10 13:57:41 primo sudo[6313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jun 10 13:57:41 primo sudo[6313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:41 primo systemd[1]: Stopping FusionDsp Daemon... Jun 10 13:57:41 primo volumio[5782]: Backend configuration: Jun 10 13:57:41 primo volumio[5782]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 10 13:57:41 primo volumio[5782]: active_config_txt: null Jun 10 13:57:41 primo volumio[5782]: camilla_host: 127.0.0.1 Jun 10 13:57:41 primo volumio[5782]: camilla_port: 9876 Jun 10 13:57:41 primo volumio[5782]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jun 10 13:57:41 primo volumio[5782]: config_dir: /data/configuration/audio_interface/fusiondsp Jun 10 13:57:41 primo volumio[5782]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 10 13:57:41 primo volumio[5782]: log_file: /tmp/camilladsp.log Jun 10 13:57:41 primo volumio[5782]: on_get_active_config: null Jun 10 13:57:41 primo volumio[5782]: on_set_active_config: null Jun 10 13:57:41 primo volumio[5782]: port: 5011 Jun 10 13:57:41 primo volumio[5782]: supported_capture_types: null Jun 10 13:57:41 primo volumio[5782]: supported_playback_types: null Jun 10 13:57:41 primo volumio[5782]: update_config_symlink: false Jun 10 13:57:41 primo volumio[5782]: update_config_txt: false Jun 10 13:57:41 primo volumio[5782]: ======== Running on http://0.0.0.0:5011 ======== Jun 10 13:57:41 primo volumio[5782]: (Press CTRL+C to quit) Jun 10 13:57:41 primo volumio[3547]: info: camilladsp spawned new process with pid 6318, instance 1, run: true Jun 10 13:57:41 primo volumio[3547]: info: RAAT: Requesting Headphone Status Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Jun 10 13:57:41 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:41 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 10 13:57:41 primo volumio[3547]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 10 13:57:41 primo volumio[3547]: info: Shairport-Sync Started Jun 10 13:57:41 primo volumio[3547]: info: Executing endpoint restartRAATSocket Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Jun 10 13:57:41 primo volumio[3547]: info: Executing endpoint tc_getconfig Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 10 13:57:41 primo volumio[3547]: info: MCU Signalled Headphone Mode Disabled Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Jun 10 13:57:41 primo volumio[3547]: info: Reporting Headphone State: false Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:41 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:41 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:41 primo vtcs[6265]: STARTING TidalConnect services, version: 1.4.0.34 Jun 10 13:57:41 primo volumio[3547]: info: camilladsp spawned new process with pid 6326, instance 1, run: true Jun 10 13:57:41 primo volumio[3547]: 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 10 13:57:41 primo volumio[3547]: info: camilladsp spawned new process with pid 6333, instance 1, run: true Jun 10 13:57:42 primo vtcs[6265]: STARTED TidalConnect services. Jun 10 13:57:42 primo volumio[3547]: info: Executing endpoint tc_connect Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 10 13:57:42 primo volumio[3547]: info: Connecting to TidalConnect Jun 10 13:57:42 primo systemd[1]: fusiondsp.service: Succeeded. Jun 10 13:57:42 primo systemd[1]: Stopped FusionDsp Daemon. Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:42 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:42 primo sudo[6313]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:42 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:42 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:42 primo volumio[3547]: info: CorePlayQueue::getTrack 0 Jun 10 13:57:42 primo volumio[3547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::servicePushState Jun 10 13:57:42 primo volumio[3547]: info: CoreStateMachine::pushState Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::volumioPushState Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::volumioGetState Jun 10 13:57:42 primo volumio[3547]: info: MRS: Pushing multiroomSync output update for this device Jun 10 13:57:42 primo volumio[3547]: info: MRS: Pushing multiroomSync output Jun 10 13:57:42 primo volumio[3547]: info: CorePlayQueue::getTrack 0 Jun 10 13:57:42 primo volumio[3547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 10 13:57:42 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:42 primo volumio[3547]: info: camilladsp spawned new process with pid 6355, instance 1, run: true Jun 10 13:57:42 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:42 primo volumio[3547]: info: Signalling Playback active due to playback status change Jun 10 13:57:42 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:42 primo volumio[3547]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jun 10 13:57:42 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:42 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:42 primo volumio[3547]: info: TidalConnect service stoped! Jun 10 13:57:42 primo volumio[3547]: info: FusionDsp - Reporting Fusion DSP Disabled Jun 10 13:57:42 primo volumio[3547]: info: Removing fusiondspeq DSP Signal Path Element Jun 10 13:57:42 primo volumio[3547]: info: Additional DSP elements updated Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 13:57:42 primo volumio[3547]: info: Updating RAAT Signal Path Jun 10 13:57:42 primo volumio[3547]: info: camilladsp spawned new process with pid 6367, instance 1, run: true Jun 10 13:57:42 primo volumio[3547]: info: Check plugin dependencies Jun 10 13:57:42 primo volumio[3547]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 13:57:42 primo volumio[3547]: info: Rename folder Jun 10 13:57:42 primo volumio[3547]: info: Move to category Jun 10 13:57:42 primo volumio[3547]: info: Folder /tmp/downloaded_plugin.zip removed Jun 10 13:57:42 primo volumio[3547]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:42 primo volumio[3547]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 10 13:57:43 primo sudo[6383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 10 13:57:43 primo sudo[6383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 13:57:43 primo volumio[3547]: info: camilladsp spawned new process with pid 6384, instance 1, run: true Jun 10 13:57:43 primo sudo[6383]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:43 primo volumio[3547]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jun 10 13:57:43 primo volumio[3547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 13:57:43 primo volumio[3547]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 10 13:57:43 primo volumio[3547]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 10 13:57:43 primo volumio[3547]: at onErrorNT (internal/child_process.js:472:16) Jun 10 13:57:43 primo volumio[3547]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jun 10 13:57:43 primo volumio[3547]: errno: -2, Jun 10 13:57:43 primo volumio[3547]: code: 'ENOENT', Jun 10 13:57:43 primo volumio[3547]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 10 13:57:43 primo volumio[3547]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 10 13:57:43 primo volumio[3547]: spawnargs: [ Jun 10 13:57:43 primo volumio[3547]: '-p', Jun 10 13:57:43 primo volumio[3547]: 9876, Jun 10 13:57:43 primo volumio[3547]: '-o', Jun 10 13:57:43 primo volumio[3547]: '/tmp/camilladsp.log', Jun 10 13:57:43 primo volumio[3547]: '-l', Jun 10 13:57:43 primo volumio[3547]: 'warn', Jun 10 13:57:43 primo volumio[3547]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 10 13:57:43 primo volumio[3547]: ] Jun 10 13:57:43 primo volumio[3547]: } Jun 10 13:57:43 primo volumio[3547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 13:57:43 primo mpd[6205]: Jun 10 13:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 13:57:43 primo systemd[1]: Started Music Player Daemon. Jun 10 13:57:43 primo sudo[5722]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:43 primo sudo[5658]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:43 primo sudo[6180]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:43 primo sudo[6028]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:43 primo sudo[5855]: pam_unix(sudo:session): session closed for user root Jun 10 13:57:44 primo sudo[6406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 13:56 Jun 10 13:57:44 primo sudo[6406]: 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"