-- Logs begin at Thu 2025-06-12 04:33:45 JST, end at Thu 2025-06-12 18:24:09 JST. -- Jun 12 18:23:01 primo volumio[3103]: info: Collecting websocket_client (from camilladsp==1.0.0) Jun 12 18:23:01 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/d3/a3/63e9329c8cc9be6153e919e17d0ef5b60d537fed78564872951b95bcc17c/websocket_client-1.6.1-py3-none-any.whl (56kB) Jun 12 18:23:01 primo volumio[3103]: info: Building wheels for collected packages: camilladsp, PyYAML Jun 12 18:23:01 primo volumio[3103]: info: Running setup.py bdist_wheel for camilladsp: started Jun 12 18:23:03 primo volumio[3103]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jun 12 18:23:03 primo volumio[3103]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-2cbnlsvo/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jun 12 18:23:03 primo volumio[3103]: info: Running setup.py bdist_wheel for PyYAML: started Jun 12 18:23:06 primo volumio[3103]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' Jun 12 18:23:06 primo volumio[3103]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 Jun 12 18:23:06 primo volumio[3103]: info: Successfully built camilladsp PyYAML Jun 12 18:23:06 primo volumio[3103]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Jun 12 18:23:07 primo volumio[3103]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Jun 12 18:23:13 primo volumio[3103]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jun 12 18:23:13 primo volumio[3103]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-fjeycyok Jun 12 18:23:16 primo volumio[3103]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jun 12 18:23:16 primo volumio[3103]: info: Collecting jsonschema (from camilladsp-plot==1.0.2) Jun 12 18:23:17 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/c1/97/c698bd9350f307daad79dd740806e1a59becd693bd11443a0f531e3229b3/jsonschema-4.17.3-py3-none-any.whl (90kB) Jun 12 18:23:17 primo volumio[3103]: info: Collecting importlib-resources>=1.4.0; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:18 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/38/71/c13ea695a4393639830bf96baea956538ba7a9d06fcce7cef10bfff20f72/importlib_resources-5.12.0-py3-none-any.whl Jun 12 18:23:18 primo volumio[3103]: info: Collecting typing-extensions; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:18 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/ec/6b/63cc3df74987c36fe26157ee12e09e8f9db4de771e0f3404263117e75b95/typing_extensions-4.7.1-py3-none-any.whl Jun 12 18:23:18 primo volumio[3103]: info: Collecting importlib-metadata; python_version < "3.8" (from jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:19 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/ff/94/64287b38c7de4c90683630338cf28f129decbba0a44f0c6db35a873c73c4/importlib_metadata-6.7.0-py3-none-any.whl Jun 12 18:23:19 primo volumio[3103]: 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 12 18:23:20 primo volumio[3103]: info: Collecting pkgutil-resolve-name>=1.3.10; python_version < "3.9" (from jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:20 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/c9/5c/3d4882ba113fd55bdba9326c1e4c62a15e674a2501de4869e6bd6301f87e/pkgutil_resolve_name-1.3.10-py3-none-any.whl Jun 12 18:23:20 primo volumio[3103]: info: Collecting pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 (from jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:21 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/64/de/375aa14daaee107f987da76ca32f7a907fea00fa8b8afb67dc09bec0de91/pyrsistent-0.19.3-py3-none-any.whl (57kB) Jun 12 18:23:21 primo volumio[3103]: info: Collecting zipp>=3.1.0; python_version < "3.10" (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) Jun 12 18:23:21 primo volumio[3103]: info: Downloading https://files.pythonhosted.org/packages/5b/fa/c9e82bbe1af6266adf08afb563905eb87cab83fde00a0a08963510621047/zipp-3.15.0-py3-none-any.whl Jun 12 18:23:21 primo volumio[3103]: info: Building wheels for collected packages: camilladsp-plot Jun 12 18:23:21 primo volumio[3103]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jun 12 18:23:23 primo volumio[3103]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jun 12 18:23:23 primo volumio[3103]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-iftxme1f/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jun 12 18:23:23 primo volumio[3103]: info: Successfully built camilladsp-plot Jun 12 18:23:24 primo volumio[3103]: info: Installing collected packages: zipp, importlib-resources, typing-extensions, importlib-metadata, pkgutil-resolve-name, pyrsistent, jsonschema, camilladsp-plot Jun 12 18:23:25 primo volumio[3103]: info: Successfully installed camilladsp-plot-1.0.2 importlib-metadata-6.7.0 importlib-resources-5.12.0 jsonschema-4.17.3 pkgutil-resolve-name-1.3.10 pyrsistent-0.19.3 typing-extensions-4.7.1 zipp-3.15.0 Jun 12 18:23:25 primo volumio[3103]: info: copying hw detection script Jun 12 18:23:25 primo volumio[3103]: info: Detected cpu architecture as aarch64 Jun 12 18:23:27 primo volumio[3103]: info: camilladsp Jun 12 18:23:27 primo sudo[1413]: 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 12 18:23:27 primo sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:27 primo sudo[1413]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:27 primo sudo[1415]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Jun 12 18:23:27 primo sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:27 primo sudo[1415]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:27 primo sudo[1417]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jun 12 18:23:27 primo sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:28 primo volumio[3103]: info: Reading package lists... Jun 12 18:23:28 primo volumio[3103]: info: Building dependency tree... Jun 12 18:23:28 primo volumio[3103]: info: Reading state information... Jun 12 18:23:30 primo volumio[3103]: info: The following NEW packages will be installed: Jun 12 18:23:30 primo volumio[3103]: info: drc Jun 12 18:23:30 primo volumio[3103]: info: 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded. Jun 12 18:23:30 primo volumio[3103]: info: Need to get 118 kB of archives. Jun 12 18:23:30 primo volumio[3103]: info: After this operation, 471 kB of additional disk space will be used. Jun 12 18:23:30 primo volumio[3103]: info: Get:1 http://deb.debian.org/debian buster/main armhf drc armhf 3.2.2~dfsg0-2 [118 kB] Jun 12 18:23:31 primo volumio[3103]: info: Fetched 118 kB in 0s (2342 kB/s) Jun 12 18:23:31 primo volumio[3103]: info: Selecting previously unselected package drc. Jun 12 18:23:31 primo volumio[3103]: [620B blob data] Jun 12 18:23:31 primo volumio[3103]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... Jun 12 18:23:31 primo volumio[3103]: info: Unpacking drc (3.2.2~dfsg0-2) ... Jun 12 18:23:32 primo volumio[3103]: info: Setting up drc (3.2.2~dfsg0-2) ... Jun 12 18:23:32 primo volumio[3103]: info: Processing triggers for man-db (2.8.5-2) ... Jun 12 18:23:33 primo sudo[1417]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:33 primo sudo[32078]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:33 primo volumio[3103]: Plugin install end detected on script Jun 12 18:23:33 primo volumio[3103]: info: Install script completed Jun 12 18:23:33 primo volumio[3103]: info: Adding reference to registry Jun 12 18:23:33 primo volumio[3103]: info: Done installing plugin. Jun 12 18:23:33 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 12 18:23:33 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 12 18:23:33 primo volumio[3103]: info: Folder /tmp/plugins removed Jun 12 18:23:33 primo volumio[3103]: info: Folder /tmp/downloaded_plugin.zip removed Jun 12 18:23:33 primo volumio[3103]: info: Folder /data/temp removed Jun 12 18:23:38 primo volumio[3103]: info: Enabling plugin fusiondsp Jun 12 18:23:38 primo volumio[3103]: info: Loading plugin "fusiondsp"... Jun 12 18:23:39 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:39 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:39 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:39 primo volumio[3103]: info: Asound.conf file written Jun 12 18:23:39 primo sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 12 18:23:39 primo sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo sudo[1562]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo kernel: Fine tdm clk setting range (0~2000000), 24575987 Jun 12 18:23:39 primo kernel: Fine spdif sysclk setting range(0~2000000), 12287994 Jun 12 18:23:39 primo kernel: out of value, fixed it Jun 12 18:23:39 primo kernel: id=0 set inskew=0 Jun 12 18:23:39 primo volumio[3103]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:eARC_TX CDS:0' : Operation not permitted Jun 12 18:23:39 primo volumio[3103]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Jun 12 18:23:39 primo volumio[3103]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Jun 12 18:23:39 primo volumio[3103]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Jun 12 18:23:39 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:39 primo sudo[1569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:39 primo sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo sudo[1569]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo volumio[3103]: info: PLUGIN START: fusiondsp Jun 12 18:23:39 primo volumio[3103]: info: Loading i18n strings for locale ja Jun 12 18:23:39 primo sudo[1571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:39 primo sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: FusionDsp - mixtype--------------------- Hardware Jun 12 18:23:39 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:39 primo systemd[1]: Stopping Music Player Daemon... Jun 12 18:23:39 primo volumio[3103]: info: Done. Jun 12 18:23:39 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:39 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:39 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:39 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:39 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:39 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:39 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:39 primo sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:39 primo sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo sudo[1586]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:39 primo sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:39 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:39 primo qobuz-connect[3458]: 20250612 18:23:39.532 [3458.3458] INFO SampleApp: Stopping Local configuration server Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 18:23:39 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:39 primo qobuz-connect[3458]: 20250612 18:23:39.549 [3458.3458] INFO SampleApp: shat down connection on UNIX socket Jun 12 18:23:39 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:39 primo sudo[1578]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:39 primo sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:39 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:39 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:39 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:39 primo sudo[1578]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:39 primo sudo[1590]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo volumio[3103]: info: FusionDsp - Jun 12 18:23:39 primo volumio[3103]: info: Asound.conf file unchanged, so no further update is needed Jun 12 18:23:39 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:39 primo sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:39 primo sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:39 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:39 primo sudo[1606]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:39 primo sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:39 primo sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:39 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 12 18:23:39 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:39 primo volumio[3103]: info: FusionDsp - undefined Jun 12 18:23:39 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:39 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:39 primo sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:39 primo sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:40 primo sudo[1616]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:40 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:40 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:40 primo sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:40 primo sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:40 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:40 primo qobuz-connect[1599]: 20250612 18:23:40.071 [1599.1599] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:40 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:40 primo systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Jun 12 18:23:40 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:40 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:40 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:40 primo sudo[1619]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:40 primo sudo[1617]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:40 primo sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:40 primo sudo[1617]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:40 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:40 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:40 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.203 [1624.1624] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:40 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.211 [1624.1624] INFO VolumeManager: [0xac895240]: Setting new playback volume: 75 Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.211 [1624.1624] INFO VolumeManager: [0xac895240]: Setting new mute state: 0 Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.211 [1624.1624] INFO QobuzConnect: [0xac895c10]: Client initialized! Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.211 [1624.1624] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.222 [1624.1624] INFO LocalConfigManager: [0xac894b20]: Starting Local Configuration server Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.222 [1624.1624] INFO SampleApp: Starting Local configuration server Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.223 [1624.1624] INFO SampleApp: Connected to UNIX socket client 0xac88aed8 Jun 12 18:23:40 primo qobuz-connect[1624]: 20250612 18:23:40.353 [1624.1624] INFO SampleApp: Playback volume changed: 75 Jun 12 18:23:40 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:41 primo volumio[3103]: info: camilladsp spawned new process with pid 1642, instance 1, run: true Jun 12 18:23:41 primo volumio[3103]: info: camilladsp service started and running in background, instance 1 Jun 12 18:23:41 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:41 primo kernel: aml_tdm_open Jun 12 18:23:41 primo kernel: Not init audio effects Jun 12 18:23:41 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 12 18:23:41 primo kernel: tdm playback mute: 1, lane_cnt = 8 Jun 12 18:23:41 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jun 12 18:23:41 primo volumio[3103]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Jun 12 18:23:41 primo volumio[3103]: info: FusionDsp loaded Jun 12 18:23:41 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 18:23:41 primo sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 12 18:23:41 primo sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:41 primo volumio[3103]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 12 18:23:41 primo volumio[3103]: info: Adding Signal Path Element [object Object] Jun 12 18:23:41 primo volumio[3103]: info: Adding fusiondspeq DSP Signal Path Element Jun 12 18:23:41 primo volumio[3103]: info: FusionDsp - ---- installed callbackRead Jun 12 18:23:41 primo volumio[3103]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 12 18:23:41 primo systemd[1]: Started FusionDsp Daemon. Jun 12 18:23:41 primo sudo[1650]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:41 primo volumio[3103]: error: FusionDsp - WebSocket error: [object Object] Jun 12 18:23:41 primo volumio[3103]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jun 12 18:23:41 primo mpd[1633]: Jun 12 18:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 18:23:41 primo systemd[1]: Started Music Player Daemon. Jun 12 18:23:41 primo sudo[1571]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:41 primo sudo[1608]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:41 primo volumio[3103]: error: MPD error: The expression evaluated to a falsy value: Jun 12 18:23:41 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:41 primo volumio[3103]: error: The expression evaluated to a falsy value: Jun 12 18:23:41 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:41 primo volumio[3103]: error: updateQueue error: null Jun 12 18:23:42 primo volumio[3103]: info: Enabling plugin fusiondsp Jun 12 18:23:42 primo volumio[3103]: info: Loading plugin "fusiondsp"... Jun 12 18:23:42 primo volumio[3103]: info: Applying required configuration parameters for plugin fusiondsp Jun 12 18:23:42 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:42 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:42 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:42 primo volumio[3103]: info: Asound.conf file unchanged, so no further update is needed Jun 12 18:23:42 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:42 primo sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:42 primo sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:42 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo volumio[3103]: info: PLUGIN START: fusiondsp Jun 12 18:23:42 primo sudo[1658]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:42 primo volumio[3103]: info: Loading i18n strings for locale ja Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo volumio[3103]: info: FusionDsp - mixtype--------------------- Hardware Jun 12 18:23:42 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:42 primo sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:42 primo sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:42 primo volumio[3103]: info: Done. Jun 12 18:23:42 primo systemd[1]: Stopping Music Player Daemon... Jun 12 18:23:42 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:42 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:42 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:42 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:42 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 18:23:42 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:42 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:42 primo sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:42 primo sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:42 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:42 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo sudo[1669]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:42 primo sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:42 primo sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:42 primo qobuz-connect[1624]: 20250612 18:23:42.528 [1624.1624] INFO SampleApp: Stopping Local configuration server Jun 12 18:23:42 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:42 primo qobuz-connect[1624]: 20250612 18:23:42.539 [1624.1624] INFO SampleApp: shat down connection on UNIX socket Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:42 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:42 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:42 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:42 primo sudo[1672]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:42 primo sudo[1670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:42 primo sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:42 primo volumio[3103]: 0+0 records in Jun 12 18:23:42 primo volumio[3103]: 0+0 records out Jun 12 18:23:42 primo volumio[3103]: 0 bytes copied, 0.000331459 s, 0.0 kB/s Jun 12 18:23:42 primo sudo[1670]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:42 primo volumio[3103]: info: camilladsp spawned new process with pid 1685, instance 1, run: true Jun 12 18:23:42 primo volumio[3103]: info: FusionDsp - Jun 12 18:23:42 primo kernel: aml_tdm_open Jun 12 18:23:42 primo kernel: Not init audio effects Jun 12 18:23:42 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jun 12 18:23:42 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jun 12 18:23:42 primo kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d2ee018, id(1), clksel(1) Jun 12 18:23:42 primo kernel: aml_dai_set_tdm_fmt(), fmt not change Jun 12 18:23:42 primo kernel: dump_pcm_setting(ffffffc03d2ee018) Jun 12 18:23:42 primo kernel: pcm_mode(1) Jun 12 18:23:42 primo kernel: sysclk(11289600) Jun 12 18:23:42 primo kernel: sysclk_bclk_ratio(4) Jun 12 18:23:42 primo kernel: bclk(2822400) Jun 12 18:23:42 primo kernel: bclk_lrclk_ratio(64) Jun 12 18:23:42 primo kernel: lrclk(44100) Jun 12 18:23:42 primo kernel: tx_mask(0x3) Jun 12 18:23:42 primo kernel: rx_mask(0x3) Jun 12 18:23:42 primo kernel: slots(2) Jun 12 18:23:42 primo kernel: slot_width(32) Jun 12 18:23:42 primo kernel: lane_mask_in(0x2) Jun 12 18:23:42 primo kernel: lane_mask_out(0x1) Jun 12 18:23:42 primo kernel: lane_oe_mask_in(0x0) Jun 12 18:23:42 primo kernel: lane_oe_mask_out(0x0) Jun 12 18:23:42 primo kernel: lane_lb_mask_in(0x0) Jun 12 18:23:42 primo kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jun 12 18:23:42 primo kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jun 12 18:23:42 primo kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Jun 12 18:23:42 primo kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jun 12 18:23:42 primo kernel: aml_dai_set_bclk_ratio, select I2S mode Jun 12 18:23:42 primo kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jun 12 18:23:42 primo kernel: aml_tdm_prepare(), reset fddr Jun 12 18:23:42 primo kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Jun 12 18:23:42 primo kernel: spdif_info: rate: 44100, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x0, ch1_r:0x0 Jun 12 18:23:42 primo kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jun 12 18:23:42 primo kernel: tdm playback mute: 0, lane_cnt = 8 Jun 12 18:23:42 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:42 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:42 primo volumio[3103]: info: FusionDsp - undefined Jun 12 18:23:42 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:42 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:42 primo qobuz-connect[1681]: 20250612 18:23:42.977 [1681.1681] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:42 primo qobuz-connect[1681]: 20250612 18:23:42.990 [1681.1681] INFO VolumeManager: [0xac081240]: Setting new playback volume: 75 Jun 12 18:23:42 primo qobuz-connect[1681]: 20250612 18:23:42.991 [1681.1681] INFO VolumeManager: [0xac081240]: Setting new mute state: 0 Jun 12 18:23:42 primo qobuz-connect[1681]: 20250612 18:23:42.991 [1681.1681] INFO QobuzConnect: [0xac081c10]: Client initialized! Jun 12 18:23:42 primo qobuz-connect[1681]: 20250612 18:23:42.992 [1681.1681] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.011 [1681.1681] INFO LocalConfigManager: [0xac080b20]: Starting Local Configuration server Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.014 [1681.1681] INFO SampleApp: Starting Local configuration server Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.015 [1681.1681] INFO SampleApp: Connected to UNIX socket client 0xac076ed8 Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:43 primo volumio[3103]: info: Asound.conf file unchanged, so no further update is needed Jun 12 18:23:43 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.128 [1681.1681] INFO SampleApp: Playback volume changed: 75 Jun 12 18:23:43 primo sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:43 primo sudo[1697]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:43 primo sudo[1700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:43 primo sudo[1700]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:43 primo sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:43 primo sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:43 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 12 18:23:43 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:43 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:43 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:43 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:43 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:43 primo sudo[1707]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:43 primo sudo[1711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:43 primo sudo[1711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.385 [1681.1681] INFO SampleApp: Stopping Local configuration server Jun 12 18:23:43 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:43 primo qobuz-connect[1681]: 20250612 18:23:43.395 [1681.1681] INFO SampleApp: shat down connection on UNIX socket Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:43 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:43 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:43 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:43 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:43 primo sudo[1711]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:43 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:43 primo sudo[1709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:43 primo sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:43 primo sudo[1709]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:43 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.546 [1719.1719] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:43 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.552 [1719.1719] INFO VolumeManager: [0xabc45240]: Setting new playback volume: 75 Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.553 [1719.1719] INFO VolumeManager: [0xabc45240]: Setting new mute state: 0 Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.553 [1719.1719] INFO QobuzConnect: [0xabc45c10]: Client initialized! Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.553 [1719.1719] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.568 [1719.1719] INFO LocalConfigManager: [0xabc44b20]: Starting Local Configuration server Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.568 [1719.1719] INFO SampleApp: Starting Local configuration server Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.569 [1719.1719] INFO SampleApp: Connected to UNIX socket client 0xabc3aed8 Jun 12 18:23:43 primo qobuz-connect[1719]: 20250612 18:23:43.695 [1719.1719] INFO SampleApp: Playback volume changed: 75 Jun 12 18:23:43 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:44 primo volumio[3103]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 12 18:23:44 primo volumio[3103]: 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 12 18:23:44 primo volumio[3103]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1730, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp service started and running in background, instance 2 Jun 12 18:23:44 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:44 primo volumio[3103]: cannot open device 'volumioHw': Device or resource busy Jun 12 18:23:44 primo volumio[3103]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 12 18:23:44 primo volumio[3103]: cannot open device 'volumioHw': Device or resource busy Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp loaded Jun 12 18:23:44 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 12 18:23:44 primo volumio[3103]: info: Adding Signal Path Element [object Object] Jun 12 18:23:44 primo volumio[3103]: info: Updating fusiondspeq DSP Signal Path Element Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - ---- installed callbackRead Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - ---- read samplerate, raw: Jun 12 18:23:44 primo volumio[3103]: error: FusionDsp - invalid sample rate Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - ---- read samplerate, raw: Jun 12 18:23:44 primo volumio[3103]: error: FusionDsp - invalid sample rate Jun 12 18:23:44 primo sudo[1745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 12 18:23:44 primo sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1753, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 12 18:23:44 primo sudo[1745]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:44 primo volumio[3103]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1767, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1774, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1781, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1789, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1812, instance 2, run: true Jun 12 18:23:44 primo volumio[3103]: info: camilladsp spawned new process with pid 1819, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1826, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1833, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1840, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1850, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1860, instance 2, run: true Jun 12 18:23:45 primo mpd[1721]: Jun 12 18:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 18:23:45 primo systemd[1]: Started Music Player Daemon. Jun 12 18:23:45 primo sudo[1662]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:45 primo sudo[1700]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:45 primo volumio[3103]: error: MPD error: The expression evaluated to a falsy value: Jun 12 18:23:45 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:45 primo volumio[3103]: error: The expression evaluated to a falsy value: Jun 12 18:23:45 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:45 primo volumio[3103]: error: updateQueue error: null Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1871, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1879, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1886, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1893, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1900, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1908, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1915, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1922, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1929, instance 2, run: true Jun 12 18:23:45 primo volumio[3103]: info: camilladsp spawned new process with pid 1936, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1943, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1950, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1957, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1964, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1971, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1980, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1987, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 1994, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2001, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2008, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2015, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2022, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2029, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2036, instance 2, run: true Jun 12 18:23:46 primo volumio[3103]: info: camilladsp spawned new process with pid 2043, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2050, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2057, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2066, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2075, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2083, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2090, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2098, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2106, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2113, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2120, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2127, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2134, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2142, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2149, instance 2, run: true Jun 12 18:23:47 primo volumio[3103]: info: camilladsp spawned new process with pid 2158, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2166, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2174, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2181, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2188, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2195, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2210, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2217, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2225, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2236, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2243, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2250, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2257, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2264, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2271, instance 2, run: true Jun 12 18:23:48 primo volumio[3103]: info: camilladsp spawned new process with pid 2278, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2285, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2293, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2300, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2307, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2314, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2321, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2329, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2337, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2344, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2351, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2359, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2366, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2373, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2380, instance 2, run: true Jun 12 18:23:49 primo volumio[3103]: info: camilladsp spawned new process with pid 2387, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2394, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2401, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2408, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2415, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2422, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2429, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2436, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2443, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2450, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2457, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2464, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2473, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2480, instance 2, run: true Jun 12 18:23:50 primo volumio[3103]: info: camilladsp spawned new process with pid 2487, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2499, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2508, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2515, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2522, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2532, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2539, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2546, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2554, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2562, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2571, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2579, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2588, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2595, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2602, instance 2, run: true Jun 12 18:23:51 primo volumio[3103]: info: camilladsp spawned new process with pid 2609, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2616, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2623, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2632, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2639, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2646, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2653, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2660, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2671, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2679, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2686, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2695, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2705, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2712, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2719, instance 2, run: true Jun 12 18:23:52 primo volumio[3103]: info: camilladsp spawned new process with pid 2726, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2733, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2740, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2747, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2754, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2761, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2768, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2775, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2782, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2790, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2797, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2806, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2813, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2823, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2832, instance 2, run: true Jun 12 18:23:53 primo volumio[3103]: info: camilladsp spawned new process with pid 2843, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2851, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2859, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2868, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2875, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2882, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2889, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2896, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2903, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2910, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2917, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2940, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2948, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2955, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2962, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2969, instance 2, run: true Jun 12 18:23:54 primo volumio[3103]: info: camilladsp spawned new process with pid 2976, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 2983, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 2990, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 2997, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3004, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3011, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3018, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3025, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3034, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3041, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3048, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3057, instance 2, run: true Jun 12 18:23:55 primo volumio[3103]: info: Enabling plugin fusiondsp Jun 12 18:23:55 primo volumio[3103]: info: Loading plugin "fusiondsp"... Jun 12 18:23:55 primo volumio[3103]: info: Applying required configuration parameters for plugin fusiondsp Jun 12 18:23:55 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:55 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:55 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:55 primo volumio[3103]: info: Asound.conf file unchanged, so no further update is needed Jun 12 18:23:55 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:55 primo sudo[3067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:55 primo sudo[3067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:55 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: PLUGIN START: fusiondsp Jun 12 18:23:55 primo sudo[3067]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:55 primo volumio[3103]: info: Loading i18n strings for locale ja Jun 12 18:23:55 primo sudo[3070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:55 primo sudo[3070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: FusionDsp - mixtype--------------------- Hardware Jun 12 18:23:55 primo volumio[3103]: info: Preparing to generate the ALSA configuration file Jun 12 18:23:55 primo volumio[3103]: info: Done. Jun 12 18:23:55 primo systemd[1]: Stopping Music Player Daemon... Jun 12 18:23:55 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:55 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:55 primo volumio[3103]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 12 18:23:55 primo volumio[3103]: info: Reading ALSA contributions from plugins. Jun 12 18:23:55 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 18:23:55 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:55 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:55 primo sudo[3077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:55 primo sudo[3077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:55 primo volumio[3103]: info: camilladsp spawned new process with pid 3087, instance 2, run: true Jun 12 18:23:55 primo sudo[3077]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:55 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:55 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:56 primo sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:56 primo qobuz-connect[1719]: 20250612 18:23:56.042 [1719.1719] INFO SampleApp: Stopping Local configuration server Jun 12 18:23:56 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:56 primo qobuz-connect[1719]: 20250612 18:23:56.053 [1719.1719] INFO SampleApp: shat down connection on UNIX socket Jun 12 18:23:56 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:56 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:56 primo sudo[3089]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo volumio[3103]: info: FusionDsp - Jun 12 18:23:56 primo sudo[3078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:56 primo sudo[3078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo sudo[3078]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo volumio[3103]: info: camilladsp spawned new process with pid 3116, instance 2, run: true Jun 12 18:23:56 primo volumio[3103]: info: FusionDsp - undefined Jun 12 18:23:56 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:56 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.461 [3106.3106] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.467 [3106.3106] INFO VolumeManager: [0xaaf14240]: Setting new playback volume: 75 Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.467 [3106.3106] INFO VolumeManager: [0xaaf14240]: Setting new mute state: 0 Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.467 [3106.3106] INFO QobuzConnect: [0xaaf14c10]: Client initialized! Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.467 [3106.3106] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.480 [3106.3106] INFO LocalConfigManager: [0xaaf13b20]: Starting Local Configuration server Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.480 [3106.3106] INFO SampleApp: Starting Local configuration server Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.481 [3106.3106] INFO SampleApp: Connected to UNIX socket client 0xaaf09ed8 Jun 12 18:23:56 primo volumio[3103]: info: camilladsp spawned new process with pid 3130, instance 2, run: true Jun 12 18:23:56 primo volumio[3103]: info: Asound.conf file unchanged, so no further update is needed Jun 12 18:23:56 primo volumio[3103]: info: Output device has changed, restarting MPD Jun 12 18:23:56 primo sudo[3139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 18:23:56 primo sudo[3139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo sudo[3139]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.611 [3106.3106] INFO SampleApp: Playback volume changed: 75 Jun 12 18:23:56 primo sudo[3144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 18:23:56 primo sudo[3144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo volumio[3103]: info: MPD Permissions set Jun 12 18:23:56 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jun 12 18:23:56 primo sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 12 18:23:56 primo systemd[1]: mpd.service: Succeeded. Jun 12 18:23:56 primo systemd[1]: Stopped Music Player Daemon. Jun 12 18:23:56 primo systemd[1]: Starting Music Player Daemon... Jun 12 18:23:56 primo volumio[3103]: info: camilladsp spawned new process with pid 3152, instance 2, run: true Jun 12 18:23:56 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo sudo[3150]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo sudo[3161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jun 12 18:23:56 primo sudo[3161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.771 [3106.3106] INFO SampleApp: Stopping Local configuration server Jun 12 18:23:56 primo systemd[1]: Stopping Volumio Qobuz Connect Service... Jun 12 18:23:56 primo qobuz-connect[3106]: 20250612 18:23:56.782 [3106.3106] INFO SampleApp: shat down connection on UNIX socket Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:56 primo volumio[3103]: info: CoreStateMachine::pushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioPushState Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo systemd[1]: qobuz-connect.service: Succeeded. Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: setDeactiveState invoked Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:56 primo systemd[1]: Stopped Volumio Qobuz Connect Service. Jun 12 18:23:56 primo volumio[3103]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 12 18:23:56 primo systemd[1]: Started Volumio Qobuz Connect Service. Jun 12 18:23:56 primo sudo[3161]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo volumio[3103]: info: camilladsp spawned new process with pid 3174, instance 2, run: true Jun 12 18:23:56 primo sudo[3153]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 18:23:56 primo sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:56 primo sudo[3153]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:56 primo volumio[3103]: info: Executing endpoint qc_getconfig Jun 12 18:23:56 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.923 [3169.3169] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jun 12 18:23:56 primo volumio[3103]: info: QobuzConnect: QOBUZ Connect daemon connected Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.930 [3169.3169] INFO VolumeManager: [0xab995240]: Setting new playback volume: 75 Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.930 [3169.3169] INFO VolumeManager: [0xab995240]: Setting new mute state: 0 Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.930 [3169.3169] INFO QobuzConnect: [0xab995c10]: Client initialized! Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.930 [3169.3169] INFO SampleApp: Starting Avahi advertising, name: Primo, service name: _qobuz-connect._tcp Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.945 [3169.3169] INFO LocalConfigManager: [0xab994b20]: Starting Local Configuration server Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.945 [3169.3169] INFO SampleApp: Starting Local configuration server Jun 12 18:23:56 primo qobuz-connect[3169]: 20250612 18:23:56.946 [3169.3169] INFO SampleApp: Connected to UNIX socket client 0xab98aed8 Jun 12 18:23:56 primo volumio[3103]: info: camilladsp spawned new process with pid 3184, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3193, instance 2, run: true Jun 12 18:23:57 primo qobuz-connect[3169]: 20250612 18:23:57.074 [3169.3169] INFO SampleApp: Playback volume changed: 75 Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3200, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: CoreCommandRouter::volumioGetState Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3210, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3218, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3225, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3232, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3239, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3300, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3311, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3318, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3325, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3333, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3340, instance 2, run: true Jun 12 18:23:57 primo volumio[3103]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 12 18:23:57 primo volumio[3103]: 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 12 18:23:57 primo volumio[3103]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jun 12 18:23:57 primo volumio[3103]: info: camilladsp spawned new process with pid 3353, instance 3, run: true Jun 12 18:23:57 primo volumio[3103]: info: camilladsp service started and running in background, instance 3 Jun 12 18:23:57 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 18:23:57 primo volumio[3103]: cannot open device 'volumioHw': Device or resource busy Jun 12 18:23:57 primo volumio[3103]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jun 12 18:23:57 primo volumio[3103]: cannot open device 'volumioHw': Device or resource busy Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp loaded Jun 12 18:23:58 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 12 18:23:58 primo volumio[3103]: info: Adding Signal Path Element [object Object] Jun 12 18:23:58 primo volumio[3103]: info: Updating fusiondspeq DSP Signal Path Element Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - ---- installed callbackRead Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - ---- read samplerate, raw: Jun 12 18:23:58 primo volumio[3103]: error: FusionDsp - invalid sample rate Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - ---- read samplerate, raw: Jun 12 18:23:58 primo volumio[3103]: error: FusionDsp - invalid sample rate Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - ---- read samplerate, raw: Jun 12 18:23:58 primo volumio[3103]: error: FusionDsp - invalid sample rate Jun 12 18:23:58 primo volumio[3103]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 12 18:23:58 primo sudo[3366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 12 18:23:58 primo sudo[3366]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3369, instance 3, run: true Jun 12 18:23:58 primo sudo[3366]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3377, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: 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 12 18:23:58 primo volumio[3103]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3384, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3391, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3398, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3405, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3413, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3420, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3427, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3434, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3441, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3448, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3455, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3462, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3469, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3476, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3483, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3491, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3502, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3509, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3516, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3523, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3530, instance 3, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3537, instance 2, run: true Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3544, instance 3, run: true Jun 12 18:23:58 primo mpd[3186]: Jun 12 18:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 18:23:58 primo systemd[1]: Started Music Player Daemon. Jun 12 18:23:58 primo sudo[3070]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:58 primo sudo[3144]: pam_unix(sudo:session): session closed for user root Jun 12 18:23:58 primo volumio[3103]: info: camilladsp spawned new process with pid 3554, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: error: MPD error: The expression evaluated to a falsy value: Jun 12 18:23:59 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:59 primo volumio[3103]: error: The expression evaluated to a falsy value: Jun 12 18:23:59 primo volumio[3103]: assert.ok(self.idling) Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3561, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: error: updateQueue error: null Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3568, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3575, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3582, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3589, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3596, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3603, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3610, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3617, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3624, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3631, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3638, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3645, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3652, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3659, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3666, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3673, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3680, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3687, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3694, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3701, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3708, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3715, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3722, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3729, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3736, instance 2, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3743, instance 3, run: true Jun 12 18:23:59 primo volumio[3103]: info: camilladsp spawned new process with pid 3750, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3757, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3764, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3771, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3778, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3785, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3792, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3799, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3806, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3813, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3820, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3827, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3834, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3841, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3848, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3855, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3862, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3869, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3876, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3883, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3890, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3897, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3904, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3911, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3918, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3925, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3932, instance 2, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3939, instance 3, run: true Jun 12 18:24:00 primo volumio[3103]: info: camilladsp spawned new process with pid 3946, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3953, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3960, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3967, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3974, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3981, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3988, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 3995, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4002, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4009, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4016, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4023, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4030, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4037, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4044, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4051, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4058, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4065, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4072, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4079, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4086, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4093, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4100, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4107, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4114, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4121, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4128, instance 2, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4135, instance 3, run: true Jun 12 18:24:01 primo volumio[3103]: info: camilladsp spawned new process with pid 4142, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4149, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4156, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4163, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4170, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4177, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4184, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4191, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4198, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4205, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4212, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4219, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4226, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4233, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4240, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4247, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4254, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4261, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4268, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4275, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4282, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4289, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4296, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4303, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4310, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4317, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4324, instance 2, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4331, instance 3, run: true Jun 12 18:24:02 primo volumio[3103]: info: camilladsp spawned new process with pid 4338, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4345, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4352, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4359, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4366, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4373, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4380, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4387, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4394, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4401, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4408, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4415, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4422, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4429, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4436, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4443, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4450, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4457, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4464, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4471, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4478, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4485, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4492, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4499, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4506, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4513, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4520, instance 2, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4527, instance 3, run: true Jun 12 18:24:03 primo volumio[3103]: info: camilladsp spawned new process with pid 4534, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4541, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4548, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4555, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4562, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4569, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4576, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4583, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4590, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4597, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4604, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4611, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4618, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4625, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4632, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4639, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4646, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4653, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4660, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4667, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4683, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4697, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4705, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4712, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4719, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4726, instance 3, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4733, instance 2, run: true Jun 12 18:24:04 primo volumio[3103]: info: camilladsp spawned new process with pid 4740, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4747, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4754, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4761, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4768, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4775, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4782, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4789, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4796, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4803, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4810, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4817, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4824, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4831, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4838, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4845, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4852, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4859, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4866, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4873, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4880, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4887, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4894, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4901, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4911, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4918, instance 3, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4925, instance 2, run: true Jun 12 18:24:05 primo volumio[3103]: info: camilladsp spawned new process with pid 4926, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4939, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4940, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4953, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4960, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4967, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4974, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4981, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4988, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 4995, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5002, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5009, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5016, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5023, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5030, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5037, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5038, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5051, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5054, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5065, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5072, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5079, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5086, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5093, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5100, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5107, instance 2, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5114, instance 3, run: true Jun 12 18:24:06 primo volumio[3103]: info: camilladsp spawned new process with pid 5121, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5128, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5135, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5142, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5149, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5156, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5163, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5170, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5177, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5184, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5191, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5198, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5205, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5212, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5219, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5226, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5233, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5240, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5247, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5254, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5261, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5268, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5275, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5282, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5289, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5296, instance 3, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5303, instance 2, run: true Jun 12 18:24:07 primo volumio[3103]: info: camilladsp spawned new process with pid 5310, instance 3, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5317, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5324, instance 3, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5331, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5338, instance 3, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5345, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5352, instance 3, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5359, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5366, instance 3, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5373, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/primo2rev2/buster/armhf Jun 12 18:24:08 primo volumio[3103]: info: FusionDsp - Stopping FusionDsp service Jun 12 18:24:08 primo volumio[3103]: info: camilladsp stopping service pid 5366... Jun 12 18:24:08 primo volumio[3103]: info: camilladsp service terminated, instance 3 Jun 12 18:24:08 primo sudo[5383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jun 12 18:24:08 primo sudo[5383]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 18:24:08 primo systemd[1]: Stopping FusionDsp Daemon... Jun 12 18:24:08 primo volumio[1652]: Backend configuration: Jun 12 18:24:08 primo volumio[1652]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 12 18:24:08 primo volumio[1652]: active_config_txt: null Jun 12 18:24:08 primo volumio[1652]: camilla_host: 127.0.0.1 Jun 12 18:24:08 primo volumio[1652]: camilla_port: 9876 Jun 12 18:24:08 primo volumio[1652]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jun 12 18:24:08 primo volumio[1652]: config_dir: /data/configuration/audio_interface/fusiondsp Jun 12 18:24:08 primo volumio[1652]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jun 12 18:24:08 primo volumio[1652]: log_file: /tmp/camilladsp.log Jun 12 18:24:08 primo volumio[1652]: on_get_active_config: null Jun 12 18:24:08 primo volumio[1652]: on_set_active_config: null Jun 12 18:24:08 primo volumio[1652]: port: 5011 Jun 12 18:24:08 primo volumio[1652]: supported_capture_types: null Jun 12 18:24:08 primo volumio[1652]: supported_playback_types: null Jun 12 18:24:08 primo volumio[1652]: update_config_symlink: false Jun 12 18:24:08 primo volumio[1652]: update_config_txt: false Jun 12 18:24:08 primo volumio[1652]: ======== Running on http://0.0.0.0:5011 ======== Jun 12 18:24:08 primo volumio[1652]: (Press CTRL+C to quit) Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5388, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5395, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5402, instance 2, run: true Jun 12 18:24:08 primo systemd[1]: fusiondsp.service: Succeeded. Jun 12 18:24:08 primo systemd[1]: Stopped FusionDsp Daemon. Jun 12 18:24:08 primo sudo[5383]: pam_unix(sudo:session): session closed for user root Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5409, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: FusionDsp - Reporting Fusion DSP Disabled Jun 12 18:24:08 primo volumio[3103]: info: Removing fusiondspeq DSP Signal Path Element Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5416, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5423, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5430, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: camilladsp spawned new process with pid 5437, instance 2, run: true Jun 12 18:24:08 primo volumio[3103]: info: Check plugin dependencies Jun 12 18:24:09 primo volumio[3103]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 12 18:24:09 primo volumio[3103]: info: Rename folder Jun 12 18:24:09 primo volumio[3103]: info: Folder /tmp/downloaded_plugin.zip removed Jun 12 18:24:09 primo volumio[3103]: info: Move to category Jun 12 18:24:09 primo volumio[3103]: info: camilladsp spawned new process with pid 5448, instance 2, run: true Jun 12 18:24:09 primo volumio[3103]: info: camilladsp spawned new process with pid undefined, instance 2, run: true Jun 12 18:24:09 primo volumio[3103]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 18:24:09 primo volumio[3103]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jun 12 18:24:09 primo volumio[3103]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jun 12 18:24:09 primo volumio[3103]: at onErrorNT (internal/child_process.js:472:16) Jun 12 18:24:09 primo volumio[3103]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jun 12 18:24:09 primo volumio[3103]: errno: -2, Jun 12 18:24:09 primo volumio[3103]: code: 'ENOENT', Jun 12 18:24:09 primo volumio[3103]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jun 12 18:24:09 primo volumio[3103]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jun 12 18:24:09 primo volumio[3103]: spawnargs: [ Jun 12 18:24:09 primo volumio[3103]: '-p', Jun 12 18:24:09 primo volumio[3103]: 9876, Jun 12 18:24:09 primo volumio[3103]: '-o', Jun 12 18:24:09 primo volumio[3103]: '/tmp/camilladsp.log', Jun 12 18:24:09 primo volumio[3103]: '-l', Jun 12 18:24:09 primo volumio[3103]: 'warn', Jun 12 18:24:09 primo volumio[3103]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jun 12 18:24:09 primo volumio[3103]: ] Jun 12 18:24:09 primo volumio[3103]: } Jun 12 18:24:09 primo volumio[3103]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 18:24:09 primo sudo[5465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 18:23 Jun 12 18:24:09 primo sudo[5465]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 07:34:23 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="c7ccfd1c436e37df77d047f13edf35d7"