-- Logs begin at Sat 2025-07-12 01:20:12 CST, end at Sat 2025-07-12 01:38:54 CST. -- Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 12 01:37:05 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 01:37:13 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:13+08:00" level=debug msg="fetched chunk 9/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:37:15 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:15+08:00" level=trace msg="sent dealer ping" Jul 12 01:37:15 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:15+08:00" level=trace msg="received dealer pong" Jul 12 01:37:27 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:27+08:00" level=debug msg="fetched chunk 10/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:37:41 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:41+08:00" level=debug msg="fetched chunk 11/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:37:45 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:45+08:00" level=trace msg="sent dealer ping" Jul 12 01:37:45 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:45+08:00" level=trace msg="received dealer pong" Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 12 01:37:50 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 12 01:37:54 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 12 01:37:54 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 12 01:37:55 volumio1900 go-librespot[1627]: time="2025-07-12T01:37:55+08:00" level=debug msg="fetched chunk 12/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:38:00 volumio1900 volumio[963]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 12 01:38:03 volumio1900 volumio[963]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 12 01:38:03 volumio1900 volumio[963]: info: Folder /tmp/plugins removed Jul 12 01:38:04 volumio1900 volumio[963]: info: Check plugin dependencies Jul 12 01:38:04 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 01:38:04 volumio1900 volumio[963]: info: Checking if plugin already exists Jul 12 01:38:04 volumio1900 volumio[963]: info: Rename folder Jul 12 01:38:04 volumio1900 volumio[963]: info: Folder /tmp/downloaded_plugin.zip removed Jul 12 01:38:04 volumio1900 volumio[963]: info: Move to category Jul 12 01:38:04 volumio1900 volumio[963]: info: Checking if install.sh is present Jul 12 01:38:04 volumio1900 volumio[963]: info: Executing install.sh Jul 12 01:38:04 volumio1900 sudo[5589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Jul 12 01:38:04 volumio1900 sudo[5589]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:04 volumio1900 volumio[963]: info: creating filters folder and copying demo filters Jul 12 01:38:04 volumio1900 volumio[963]: info: copying demo flters Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/ Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/ Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Jul 12 01:38:04 volumio1900 sudo[5614]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Jul 12 01:38:04 volumio1900 sudo[5614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/PEQ/ Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/convfir/ Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/convfir/kef.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/convfir/test.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/ Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/.Bass-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/.Classic-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/.Voice-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: ./presets/EQ15/.Rock-FusionDsp.json Jul 12 01:38:04 volumio1900 volumio[963]: info: Installing/fusiondsp dependencies Jul 12 01:38:05 volumio1900 volumio[963]: info: Hit:1 https://deb.nodesource.com/node_14.x buster InRelease Jul 12 01:38:05 volumio1900 volumio[963]: info: Hit:2 http://deb.debian.org/debian buster InRelease Jul 12 01:38:08 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:08+08:00" level=debug msg="fetched chunk 13/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:38:09 volumio1900 volumio[963]: info: Reading package lists... Jul 12 01:38:09 volumio1900 volumio[963]: info: Building dependency tree... Jul 12 01:38:09 volumio1900 volumio[963]: info: Reading state information... Jul 12 01:38:09 volumio1900 volumio[963]: info: All packages are up to date. Jul 12 01:38:09 volumio1900 sudo[5614]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:09 volumio1900 sudo[5823]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Jul 12 01:38:09 volumio1900 sudo[5823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:09 volumio1900 volumio[963]: info: Reading package lists... Jul 12 01:38:10 volumio1900 volumio[963]: info: Building dependency tree... Jul 12 01:38:10 volumio1900 volumio[963]: info: Reading state information... Jul 12 01:38:11 volumio1900 volumio[963]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Jul 12 01:38:11 volumio1900 sudo[5823]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:11 volumio1900 volumio[963]: info: python3-pip is already the newest version (18.1-5). Jul 12 01:38:11 volumio1900 volumio[963]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jul 12 01:38:11 volumio1900 sudo[5841]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Jul 12 01:38:11 volumio1900 sudo[5841]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:11 volumio1900 volumio[963]: info: lib/systemd/system/fusiondsp.service Jul 12 01:38:11 volumio1900 sudo[5841]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:14 volumio1900 volumio[963]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Jul 12 01:38:14 volumio1900 volumio[963]: info: more info at http://www.winimage.com/zLibDll/unzip.html Jul 12 01:38:14 volumio1900 volumio[963]: info: Jul 12 01:38:14 volumio1900 volumio[963]: info: cgui-1.0.0.zip opened Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/LICENSE.txt Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/README.md Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/build/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/robots.txt Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/logo512.png Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/asset-manifest.json Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/index.html Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/.put_statics_here Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/favicon.ico Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/logo192.png Jul 12 01:38:14 volumio1900 sudo[5845]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Jul 12 01:38:14 volumio1900 sudo[5845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/manifest.json Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/css-variables.css Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/service-worker.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/build/static/ Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/build/static/css/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Jul 12 01:38:14 volumio1900 sudo[5845]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/main.1503f6cd.css Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/build/static/media/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/build/static/js/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.71e11955.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Jul 12 01:38:14 volumio1900 sudo[5847]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Jul 12 01:38:14 volumio1900 sudo[5847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.71e11955.js.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Jul 12 01:38:14 volumio1900 sudo[5847]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/config/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/config/camillagui.yml Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/config/gui-config.yml Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/backend/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/filters_test.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/version.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/filters.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/settings.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/views.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/filterdefaults.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__init__.py Jul 12 01:38:14 volumio1900 volumio[963]: info: creating directory: cgui/backend/__pycache__/ Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Jul 12 01:38:14 volumio1900 sudo[5849]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Jul 12 01:38:14 volumio1900 sudo[5849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/routes.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/backend/filemanagement.py Jul 12 01:38:14 volumio1900 volumio[963]: info: extracting: cgui/main.py Jul 12 01:38:14 volumio1900 sudo[5849]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:15 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:15+08:00" level=trace msg="sent dealer ping" Jul 12 01:38:15 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:15+08:00" level=trace msg="received dealer pong" Jul 12 01:38:16 volumio1900 volumio[963]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Jul 12 01:38:16 volumio1900 volumio[963]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-s65_4dy0 Jul 12 01:38:19 volumio1900 volumio[963]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp==1.0.0 from git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 in /usr/local/lib/python3.7/dist-packages Jul 12 01:38:19 volumio1900 volumio[963]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Jul 12 01:38:19 volumio1900 volumio[963]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Jul 12 01:38:19 volumio1900 volumio[963]: info: Building wheels for collected packages: camilladsp Jul 12 01:38:19 volumio1900 volumio[963]: info: Running setup.py bdist_wheel for camilladsp: started Jul 12 01:38:20 volumio1900 volumio[963]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Jul 12 01:38:20 volumio1900 volumio[963]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-5t4vlm2m/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Jul 12 01:38:20 volumio1900 volumio[963]: info: Successfully built camilladsp Jul 12 01:38:21 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:21+08:00" level=debug msg="fetched chunk 14/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:38:24 volumio1900 volumio[963]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Jul 12 01:38:24 volumio1900 volumio[963]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-_xshfy08 Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied (use --upgrade to upgrade): camilladsp-plot==1.0.2 from git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 in /usr/local/lib/python3.7/dist-packages Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: importlib-resources>=1.4.0; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (5.12.0) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: importlib-metadata; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (6.7.0) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: typing-extensions; python_version < "3.8" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (4.7.1) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: pkgutil-resolve-name>=1.3.10; python_version < "3.9" in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (1.3.10) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: pyrsistent!=0.17.0,!=0.17.1,!=0.17.2,>=0.14.0 in /usr/local/lib/python3.7/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (0.19.3) Jul 12 01:38:25 volumio1900 volumio[963]: info: Requirement already satisfied: zipp>=3.1.0; python_version < "3.10" in /usr/local/lib/python3.7/dist-packages (from importlib-resources>=1.4.0; python_version < "3.9"->jsonschema->camilladsp-plot==1.0.2) (3.15.0) Jul 12 01:38:26 volumio1900 volumio[963]: info: Building wheels for collected packages: camilladsp-plot Jul 12 01:38:26 volumio1900 volumio[963]: info: Running setup.py bdist_wheel for camilladsp-plot: started Jul 12 01:38:26 volumio1900 volumio[963]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Jul 12 01:38:26 volumio1900 volumio[963]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-qslzppqq/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Jul 12 01:38:26 volumio1900 volumio[963]: info: Successfully built camilladsp-plot Jul 12 01:38:27 volumio1900 volumio[963]: info: copying hw detection script Jul 12 01:38:27 volumio1900 volumio[963]: info: Detected cpu architecture as x86_64 Jul 12 01:38:29 volumio1900 sudo[5980]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Jul 12 01:38:29 volumio1900 sudo[5980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:29 volumio1900 volumio[963]: info: Reading package lists... Jul 12 01:38:30 volumio1900 volumio[963]: info: Building dependency tree... Jul 12 01:38:30 volumio1900 volumio[963]: info: Reading state information... Jul 12 01:38:31 volumio1900 volumio[963]: info: drc is already the newest version (3.2.2~dfsg0-2). Jul 12 01:38:31 volumio1900 sudo[5980]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:31 volumio1900 sudo[5589]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:31 volumio1900 volumio[963]: info: 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. Jul 12 01:38:31 volumio1900 volumio[963]: info: Install script completed Jul 12 01:38:31 volumio1900 volumio[963]: info: Adding reference to registry Jul 12 01:38:31 volumio1900 volumio[963]: info: Done installing plugin. Jul 12 01:38:31 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 12 01:38:31 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 12 01:38:31 volumio1900 volumio[963]: info: Folder /tmp/plugins removed Jul 12 01:38:31 volumio1900 volumio[963]: info: Folder /tmp/downloaded_plugin.zip removed Jul 12 01:38:31 volumio1900 volumio[963]: Plugin install end detected on script Jul 12 01:38:31 volumio1900 volumio[963]: info: Folder /data/temp removed Jul 12 01:38:34 volumio1900 volumio[963]: info: Enabling plugin fusiondsp Jul 12 01:38:34 volumio1900 volumio[963]: info: Loading plugin "fusiondsp"... Jul 12 01:38:34 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:34+08:00" level=debug msg="fetched chunk 15/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:38:35 volumio1900 volumio[963]: info: Preparing to generate the ALSA configuration file Jul 12 01:38:35 volumio1900 volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 01:38:35 volumio1900 volumio[963]: info: Reading ALSA contributions from plugins. Jul 12 01:38:35 volumio1900 volumio[963]: info: Asound.conf file written Jul 12 01:38:35 volumio1900 sudo[5999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 12 01:38:35 volumio1900 sudo[5999]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 sudo[5999]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 volumio[963]: info: Output device has changed, restarting MPD Jul 12 01:38:35 volumio1900 sudo[6004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 01:38:35 volumio1900 sudo[6004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 volumio[963]: info: Output device has changed, restarting Shairport Sync Jul 12 01:38:35 volumio1900 sudo[6004]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:35 volumio1900 sudo[6007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 01:38:35 volumio1900 sudo[6007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 systemd[1]: musicservicesshield.service: Succeeded. Jul 12 01:38:35 volumio1900 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 12 01:38:35 volumio1900 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 12 01:38:35 volumio1900 systemd[1]: Stopping Music Player Daemon... Jul 12 01:38:35 volumio1900 systemd[1]: mpd.service: Succeeded. Jul 12 01:38:35 volumio1900 volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 01:38:35 volumio1900 systemd[1]: Stopped Music Player Daemon. Jul 12 01:38:35 volumio1900 volumio[963]: info: PLUGIN START: fusiondsp Jul 12 01:38:35 volumio1900 volumio[963]: info: Loading i18n strings for locale zh_TW Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: FusionDsp - mixtype--------------------- Hardware Jul 12 01:38:35 volumio1900 volumio[963]: info: Preparing to generate the ALSA configuration file Jul 12 01:38:35 volumio1900 volumio[963]: info: Done. Jul 12 01:38:35 volumio1900 volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 01:38:35 volumio1900 volumio[963]: info: Reading ALSA contributions from plugins. Jul 12 01:38:35 volumio1900 systemd[1]: Starting Music Player Daemon... Jul 12 01:38:35 volumio1900 volumio[963]: info: MPD Permissions set Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:38:35 volumio1900 volumio[963]: info: Starting Shairport Sync Jul 12 01:38:35 volumio1900 sudo[6012]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 01:38:35 volumio1900 sudo[6012]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:35+08:00" level=trace msg="received accesspoint ping" Jul 12 01:38:35 volumio1900 volumio[963]: info: Asound.conf file unchanged, so no further update is needed Jul 12 01:38:35 volumio1900 volumio[963]: info: Output device has changed, restarting MPD Jul 12 01:38:35 volumio1900 sudo[6012]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:35+08:00" level=trace msg="received accesspoint pong ack" Jul 12 01:38:35 volumio1900 sudo[6022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 01:38:35 volumio1900 sudo[6022]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 sudo[6026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 01:38:35 volumio1900 sudo[6026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 01:38:35 volumio1900 systemd[1]: shairport-sync.service: Succeeded. Jul 12 01:38:35 volumio1900 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:35 volumio1900 volumio[963]: info: Output device has changed, restarting Shairport Sync Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:35 volumio1900 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:35 volumio1900 sudo[6022]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 sudo[6029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 01:38:35 volumio1900 sudo[6029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 sudo[6026]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 12 01:38:35 volumio1900 systemd[1]: mpd.service: Succeeded. Jul 12 01:38:35 volumio1900 systemd[1]: Stopped Music Player Daemon. Jul 12 01:38:35 volumio1900 systemd[1]: Starting Music Player Daemon... Jul 12 01:38:35 volumio1900 volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 01:38:35 volumio1900 volumio[963]: info: MPD Permissions set Jul 12 01:38:35 volumio1900 volumio[963]: info: Shairport-Sync Started Jul 12 01:38:35 volumio1900 volumio[963]: info: FusionDsp - Jul 12 01:38:35 volumio1900 sudo[6036]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 01:38:35 volumio1900 sudo[6036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 sudo[6036]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 volumio[963]: info: FusionDsp - Jul 12 01:38:35 volumio1900 volumio[963]: info: FusionDsp - undefined Jul 12 01:38:35 volumio1900 volumio[963]: info: FusionDsp - undefined Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 01:38:35 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:35 volumio1900 volumio[963]: info: Starting Shairport Sync Jul 12 01:38:35 volumio1900 sudo[6047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 01:38:35 volumio1900 sudo[6047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:35 volumio1900 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 01:38:35 volumio1900 systemd[1]: shairport-sync.service: Succeeded. Jul 12 01:38:35 volumio1900 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:35 volumio1900 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:35 volumio1900 sudo[6047]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:35 volumio1900 volumio[963]: info: Shairport-Sync Started Jul 12 01:38:36 volumio1900 mpd[6039]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 12 01:38:36 volumio1900 mpd[6039]: output: No 'audio_output' defined in config file Jul 12 01:38:36 volumio1900 mpd[6039]: output: Successfully detected a sndio audio device Jul 12 01:38:36 volumio1900 mpd[6039]: zeroconf: No global port, disabling zeroconf Jul 12 01:38:36 volumio1900 systemd[1]: Started Music Player Daemon. Jul 12 01:38:36 volumio1900 sudo[6007]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:36 volumio1900 sudo[6029]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:36 volumio1900 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 12 01:38:36 volumio1900 volumio[963]: error: MPD error: The expression evaluated to a falsy value: Jul 12 01:38:36 volumio1900 volumio[963]: assert.ok(self.idling) Jul 12 01:38:36 volumio1900 volumio[963]: error: The expression evaluated to a falsy value: Jul 12 01:38:36 volumio1900 volumio[963]: assert.ok(self.idling) Jul 12 01:38:36 volumio1900 volumio[963]: error: updateQueue error: null Jul 12 01:38:37 volumio1900 volumio[6055]: cset: --> shielding system active with Jul 12 01:38:37 volumio1900 volumio[6055]: cset: "system" cpuset of CPUSPEC(1-3) with 122 tasks running Jul 12 01:38:37 volumio1900 volumio[6055]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 12 01:38:37 volumio1900 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 12 01:38:37 volumio1900 volumio[963]: info: Enabling plugin fusiondsp Jul 12 01:38:37 volumio1900 volumio[963]: info: Loading plugin "fusiondsp"... Jul 12 01:38:37 volumio1900 volumio[963]: info: Applying required configuration parameters for plugin fusiondsp Jul 12 01:38:37 volumio1900 volumio[963]: info: Preparing to generate the ALSA configuration file Jul 12 01:38:37 volumio1900 volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 01:38:37 volumio1900 volumio[963]: info: Reading ALSA contributions from plugins. Jul 12 01:38:37 volumio1900 volumio[963]: info: Asound.conf file unchanged, so no further update is needed Jul 12 01:38:37 volumio1900 volumio[963]: info: Output device has changed, restarting MPD Jul 12 01:38:37 volumio1900 sudo[6071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 01:38:37 volumio1900 sudo[6071]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:37 volumio1900 sudo[6071]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:37 volumio1900 volumio[963]: info: Output device has changed, restarting Shairport Sync Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:37 volumio1900 sudo[6074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 01:38:37 volumio1900 sudo[6074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:37 volumio1900 systemd[1]: musicservicesshield.service: Succeeded. Jul 12 01:38:37 volumio1900 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jul 12 01:38:37 volumio1900 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jul 12 01:38:37 volumio1900 systemd[1]: Stopping Music Player Daemon... Jul 12 01:38:37 volumio1900 systemd[1]: mpd.service: Succeeded. Jul 12 01:38:37 volumio1900 systemd[1]: Stopped Music Player Daemon. Jul 12 01:38:37 volumio1900 volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 01:38:37 volumio1900 volumio[963]: info: PLUGIN START: fusiondsp Jul 12 01:38:37 volumio1900 volumio[963]: info: Loading i18n strings for locale zh_TW Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - mixtype--------------------- Hardware Jul 12 01:38:37 volumio1900 volumio[963]: info: Preparing to generate the ALSA configuration file Jul 12 01:38:37 volumio1900 volumio[963]: info: Done. Jul 12 01:38:37 volumio1900 systemd[1]: Starting Music Player Daemon... Jul 12 01:38:37 volumio1900 volumio[963]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 12 01:38:37 volumio1900 volumio[963]: info: Reading ALSA contributions from plugins. Jul 12 01:38:37 volumio1900 volumio[963]: info: MPD Permissions set Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:38:37 volumio1900 volumio[963]: info: camilladsp service started and running in background, instance 1 Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 sudo[6079]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 01:38:37 volumio1900 sudo[6079]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:37 volumio1900 sudo[6079]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:37 volumio1900 volumio[963]: cannot open device 'volumioHw': Device or resource busy Jul 12 01:38:37 volumio1900 volumio[963]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 12 01:38:37 volumio1900 volumio[963]: cannot open device 'volumioHw': Device or resource busy Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp loaded Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 01:38:37 volumio1900 sudo[6096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 12 01:38:37 volumio1900 sudo[6096]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 12 01:38:37 volumio1900 volumio[963]: info: Adding Signal Path Element [object Object] Jul 12 01:38:37 volumio1900 volumio[963]: info: Adding fusiondspeq DSP Signal Path Element Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - ---- installed callbackRead Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 systemd[1]: Started FusionDsp Daemon. Jul 12 01:38:37 volumio1900 sudo[6096]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 01:38:37 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:37 volumio1900 volumio[963]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - Jul 12 01:38:37 volumio1900 volumio[963]: error: FusionDsp - WebSocket error: [object Object] Jul 12 01:38:37 volumio1900 volumio[963]: info: Starting Shairport Sync Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - undefined Jul 12 01:38:37 volumio1900 sudo[6106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 01:38:37 volumio1900 sudo[6106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:37 volumio1900 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 01:38:37 volumio1900 systemd[1]: shairport-sync.service: Succeeded. Jul 12 01:38:37 volumio1900 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:37 volumio1900 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:37 volumio1900 sudo[6106]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - Jul 12 01:38:37 volumio1900 volumio[963]: info: FusionDsp - undefined Jul 12 01:38:38 volumio1900 volumio[963]: info: Shairport-Sync Started Jul 12 01:38:38 volumio1900 volumio[963]: info: Asound.conf file unchanged, so no further update is needed Jul 12 01:38:38 volumio1900 volumio[963]: info: Output device has changed, restarting MPD Jul 12 01:38:38 volumio1900 volumio[963]: info: Output device has changed, restarting Shairport Sync Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:38 volumio1900 sudo[6112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 12 01:38:38 volumio1900 sudo[6114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 12 01:38:38 volumio1900 sudo[6112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:38 volumio1900 sudo[6114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:38 volumio1900 sudo[6112]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:38 volumio1900 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 12 01:38:38 volumio1900 systemd[1]: mpd.service: Succeeded. Jul 12 01:38:38 volumio1900 systemd[1]: Stopped Music Player Daemon. Jul 12 01:38:38 volumio1900 volumio[963]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 12 01:38:38 volumio1900 volumio[963]: info: MPD Permissions set Jul 12 01:38:38 volumio1900 systemd[1]: Starting Music Player Daemon... Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 12 01:38:38 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:38 volumio1900 sudo[6120]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 12 01:38:38 volumio1900 volumio[963]: info: Starting Shairport Sync Jul 12 01:38:38 volumio1900 sudo[6120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:38 volumio1900 sudo[6120]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:38 volumio1900 sudo[6130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 12 01:38:38 volumio1900 sudo[6130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:38 volumio1900 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 12 01:38:38 volumio1900 systemd[1]: shairport-sync.service: Succeeded. Jul 12 01:38:38 volumio1900 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:38 volumio1900 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 12 01:38:38 volumio1900 sudo[6130]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:38 volumio1900 volumio[963]: info: Shairport-Sync Started Jul 12 01:38:38 volumio1900 volumio[963]: 0+0 records in Jul 12 01:38:38 volumio1900 volumio[963]: 0+0 records out Jul 12 01:38:38 volumio1900 volumio[963]: 0 bytes copied, 0.000110735 s, 0.0 kB/s Jul 12 01:38:39 volumio1900 mpd[6129]: Jul 12 01:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 12 01:38:39 volumio1900 systemd[1]: Started Music Player Daemon. Jul 12 01:38:39 volumio1900 sudo[6074]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:39 volumio1900 sudo[6114]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:39 volumio1900 volumio[963]: error: MPD error: The expression evaluated to a falsy value: Jul 12 01:38:39 volumio1900 volumio[963]: assert.ok(self.idling) Jul 12 01:38:39 volumio1900 volumio[963]: error: The expression evaluated to a falsy value: Jul 12 01:38:39 volumio1900 volumio[963]: assert.ok(self.idling) Jul 12 01:38:39 volumio1900 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jul 12 01:38:39 volumio1900 volumio[963]: error: updateQueue error: null Jul 12 01:38:39 volumio1900 volumio[963]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 12 01:38:39 volumio1900 volumio[963]: 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 Jul 12 01:38:39 volumio1900 volumio[963]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 12 01:38:39 volumio1900 volumio[963]: info: camilladsp service started and running in background, instance 2 Jul 12 01:38:39 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 12 01:38:39 volumio1900 volumio[963]: cannot open device 'volumioHw': Device or resource busy Jul 12 01:38:39 volumio1900 volumio[963]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 12 01:38:39 volumio1900 volumio[963]: cannot open device 'volumioHw': Device or resource busy Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp loaded Jul 12 01:38:39 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 12 01:38:39 volumio1900 volumio[963]: info: Adding Signal Path Element [object Object] Jul 12 01:38:39 volumio1900 volumio[963]: info: Updating fusiondspeq DSP Signal Path Element Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp - ---- installed callbackRead Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp - ---- read samplerate, raw: Jul 12 01:38:39 volumio1900 sudo[6318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 12 01:38:39 volumio1900 sudo[6318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:39 volumio1900 volumio[963]: error: FusionDsp - invalid sample rate Jul 12 01:38:39 volumio1900 volumio[963]: info: FusionDsp - ---- read samplerate, raw: Jul 12 01:38:39 volumio1900 volumio[963]: error: FusionDsp - invalid sample rate Jul 12 01:38:39 volumio1900 volumio[963]: error: FusionDsp - WebSocket error: [object Object] Jul 12 01:38:39 volumio1900 sudo[6318]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:39 volumio1900 volumio[963]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jul 12 01:38:39 volumio1900 volumio[6245]: cset: --> shielding system active with Jul 12 01:38:39 volumio1900 volumio[6245]: cset: "system" cpuset of CPUSPEC(1-3) with 123 tasks running Jul 12 01:38:39 volumio1900 volumio[6245]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jul 12 01:38:39 volumio1900 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jul 12 01:38:45 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:45+08:00" level=trace msg="sent dealer ping" Jul 12 01:38:45 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:45+08:00" level=trace msg="received dealer pong" Jul 12 01:38:47 volumio1900 go-librespot[1627]: time="2025-07-12T01:38:47+08:00" level=debug msg="fetched chunk 16/17, size: 524288" uri="spotify:track:2FQE7XVMDtuHjkjY0D1uwj" Jul 12 01:38:50 volumio1900 volumio[963]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 12 01:38:53 volumio1900 volumio[963]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Jul 12 01:38:53 volumio1900 volumio[963]: info: FusionDsp - Stopping FusionDsp service Jul 12 01:38:53 volumio1900 volumio[963]: info: camilladsp service terminated, instance 2 Jul 12 01:38:53 volumio1900 sudo[11246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jul 12 01:38:53 volumio1900 sudo[11246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 12 01:38:54 volumio1900 systemd[1]: Stopping FusionDsp Daemon... Jul 12 01:38:54 volumio1900 volumio[6098]: Backend configuration: Jul 12 01:38:54 volumio1900 volumio[6098]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 12 01:38:54 volumio1900 volumio[6098]: active_config_txt: null Jul 12 01:38:54 volumio1900 volumio[6098]: camilla_host: 127.0.0.1 Jul 12 01:38:54 volumio1900 volumio[6098]: camilla_port: 9876 Jul 12 01:38:54 volumio1900 volumio[6098]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jul 12 01:38:54 volumio1900 volumio[6098]: config_dir: /data/configuration/audio_interface/fusiondsp Jul 12 01:38:54 volumio1900 volumio[6098]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 12 01:38:54 volumio1900 volumio[6098]: log_file: /tmp/camilladsp.log Jul 12 01:38:54 volumio1900 volumio[6098]: on_get_active_config: null Jul 12 01:38:54 volumio1900 volumio[6098]: on_set_active_config: null Jul 12 01:38:54 volumio1900 volumio[6098]: port: 5011 Jul 12 01:38:54 volumio1900 volumio[6098]: supported_capture_types: null Jul 12 01:38:54 volumio1900 volumio[6098]: supported_playback_types: null Jul 12 01:38:54 volumio1900 volumio[6098]: update_config_symlink: false Jul 12 01:38:54 volumio1900 volumio[6098]: update_config_txt: false Jul 12 01:38:54 volumio1900 volumio[6098]: ======== Running on http://0.0.0.0:5011 ======== Jul 12 01:38:54 volumio1900 volumio[6098]: (Press CTRL+C to quit) Jul 12 01:38:54 volumio1900 systemd[1]: fusiondsp.service: Succeeded. Jul 12 01:38:54 volumio1900 systemd[1]: Stopped FusionDsp Daemon. Jul 12 01:38:54 volumio1900 sudo[11246]: pam_unix(sudo:session): session closed for user root Jul 12 01:38:54 volumio1900 volumio[963]: info: FusionDsp - Reporting Fusion DSP Disabled Jul 12 01:38:54 volumio1900 volumio[963]: info: Removing fusiondspeq DSP Signal Path Element Jul 12 01:38:54 volumio1900 volumio[963]: info: Check plugin dependencies Jul 12 01:38:54 volumio1900 volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 12 01:38:54 volumio1900 volumio[963]: info: Rename folder Jul 12 01:38:54 volumio1900 volumio[963]: info: Folder /tmp/downloaded_plugin.zip removed Jul 12 01:38:54 volumio1900 volumio[963]: info: Move to category Jul 12 01:38:54 volumio1900 volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 01:38:54 volumio1900 volumio[963]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 12 01:38:54 volumio1900 volumio[963]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Jul 12 01:38:54 volumio1900 volumio[963]: at onErrorNT (internal/child_process.js:472:16) Jul 12 01:38:54 volumio1900 volumio[963]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Jul 12 01:38:54 volumio1900 volumio[963]: errno: -2, Jul 12 01:38:54 volumio1900 volumio[963]: code: 'ENOENT', Jul 12 01:38:54 volumio1900 volumio[963]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 12 01:38:54 volumio1900 volumio[963]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 12 01:38:54 volumio1900 volumio[963]: spawnargs: [ Jul 12 01:38:54 volumio1900 volumio[963]: '-p', Jul 12 01:38:54 volumio1900 volumio[963]: 9876, Jul 12 01:38:54 volumio1900 volumio[963]: '-o', Jul 12 01:38:54 volumio1900 volumio[963]: '/tmp/camilladsp.log', Jul 12 01:38:54 volumio1900 volumio[963]: '-l', Jul 12 01:38:54 volumio1900 volumio[963]: 'warn', Jul 12 01:38:54 volumio1900 volumio[963]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 12 01:38:54 volumio1900 volumio[963]: ] Jul 12 01:38:54 volumio1900 volumio[963]: } Jul 12 01:38:54 volumio1900 volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 12 01:38:54 volumio1900 sudo[11320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-12 01:37 Jul 12 01:38:54 volumio1900 sudo[11320]: 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="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:41:06 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="097e6b8b481766e7a0f9527e4a34a29b"