-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sat 2025-12-06 22:59:46 CET. --
Dec 06 22:58:00 volumio go-librespot[1204]: time="2025-12-06T22:58:00+01:00" level=trace msg="sent dealer ping"
Dec 06 22:58:00 volumio go-librespot[1204]: time="2025-12-06T22:58:00+01:00" level=trace msg="received dealer pong"
Dec 06 22:58:04 volumio volumio[829]: Upnp client error: Error: This socket has been ended by the other party
Dec 06 22:58:04 volumio volumio[829]: Upnp client error: Error: This socket has been ended by the other party
Dec 06 22:58:04 volumio volumio[829]: Upnp client error: Error: This socket has been ended by the other party
Dec 06 22:58:09 volumio volumio[829]: info: Checking if install.sh is present
Dec 06 22:58:09 volumio volumio[829]: info: Executing install.sh
Dec 06 22:58:09 volumio volumio[829]: Upnp client error: Error: This socket has been ended by the other party
Dec 06 22:58:09 volumio sudo[20541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh
Dec 06 22:58:09 volumio sudo[20541]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:09 volumio volumio[829]: info: creating filters folder and copying demo filters
Dec 06 22:58:09 volumio volumio[829]: info: copying demo flters
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/.Bass-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/.Classic-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/.Voice-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/2XEQ15/.Rock-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/PEQ/
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/convfir/
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/convfir/kef.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/convfir/test.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/.Bass-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/.Classic-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/.Voice-FusionDsp.json
Dec 06 22:58:13 volumio volumio[829]: info: ./presets/EQ15/.Rock-FusionDsp.json
Dec 06 22:58:13 volumio sudo[20588]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update
Dec 06 22:58:13 volumio sudo[20588]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:13 volumio volumio[829]: info: Installing/fusiondsp dependencies
Dec 06 22:58:13 volumio volumio[829]: info: Ign:1 http://raspbian.raspberrypi.org/raspbian buster InRelease
Dec 06 22:58:13 volumio volumio[829]: info: Get:2 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB]
Dec 06 22:58:13 volumio volumio[829]: info: Err:3 http://raspbian.raspberrypi.org/raspbian buster Release
Dec 06 22:58:13 volumio volumio[829]: info: 404 Not Found [IP: 93.93.128.193 80]
Dec 06 22:58:13 volumio volumio[829]: info: Hit:4 https://deb.nodesource.com/node_14.x buster InRelease
Dec 06 22:58:14 volumio volumio[829]: info: Get:5 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB]
Dec 06 22:58:22 volumio volumio[829]: info: Reading package lists...
Dec 06 22:58:22 volumio sudo[20588]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:22 volumio sudo[20765]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip
Dec 06 22:58:22 volumio sudo[20765]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:23 volumio volumio[829]: info: Reading package lists...
Dec 06 22:58:23 volumio volumio[829]: info: Building dependency tree...
Dec 06 22:58:24 volumio volumio[829]: info: Reading state information...
Dec 06 22:58:25 volumio volumio[829]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1).
Dec 06 22:58:25 volumio sudo[20765]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:25 volumio volumio[829]: info: python3-pip is already the newest version (18.1-5+rpt1).
Dec 06 22:58:25 volumio volumio[829]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded.
Dec 06 22:58:25 volumio sudo[20773]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C /
Dec 06 22:58:25 volumio sudo[20773]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:25 volumio volumio[829]: info: lib/systemd/system/fusiondsp.service
Dec 06 22:58:25 volumio sudo[20773]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:27 volumio volumio[829]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant
Dec 06 22:58:27 volumio volumio[829]: info: more info at http://www.winimage.com/zLibDll/unzip.html
Dec 06 22:58:27 volumio volumio[829]: info:
Dec 06 22:58:27 volumio volumio[829]: info: cgui-1.0.0.zip opened
Dec 06 22:58:27 volumio sudo[20781]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui
Dec 06 22:58:27 volumio sudo[20781]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/LICENSE.txt
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/README.md
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/build/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/robots.txt
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/logo512.png
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/asset-manifest.json
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/index.html
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/.put_statics_here
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/favicon.ico
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/logo192.png
Dec 06 22:58:27 volumio sudo[20781]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/manifest.json
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/css-variables.css
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/service-worker.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/build/static/
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/build/static/css/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/main.1503f6cd.css
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/build/static/media/
Dec 06 22:58:27 volumio sudo[20783]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui
Dec 06 22:58:27 volumio sudo[20783]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/build/static/js/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.71e11955.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js
Dec 06 22:58:27 volumio sudo[20783]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.71e11955.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/config/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/config/camillagui.yml
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/config/gui-config.yml
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/backend/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/filters_test.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/version.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/filters.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/settings.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/views.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/filterdefaults.py
Dec 06 22:58:27 volumio sudo[20785]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip
Dec 06 22:58:27 volumio sudo[20785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__init__.py
Dec 06 22:58:27 volumio volumio[829]: info: creating directory: cgui/backend/__pycache__/
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/routes.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/backend/filemanagement.py
Dec 06 22:58:27 volumio volumio[829]: info: extracting: cgui/main.py
Dec 06 22:58:27 volumio sudo[20785]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:30 volumio go-librespot[1204]: time="2025-12-06T22:58:30+01:00" level=trace msg="sent dealer ping"
Dec 06 22:58:30 volumio go-librespot[1204]: time="2025-12-06T22:58:30+01:00" level=trace msg="received dealer pong"
Dec 06 22:58:31 volumio go-librespot[1204]: time="2025-12-06T22:58:31+01:00" level=trace msg="received accesspoint ping"
Dec 06 22:58:31 volumio go-librespot[1204]: time="2025-12-06T22:58:31+01:00" level=trace msg="received accesspoint pong ack"
Dec 06 22:58:41 volumio volumio[829]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
Dec 06 22:58:41 volumio volumio[829]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0
Dec 06 22:58:41 volumio volumio[829]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-fi5lxbd7
Dec 06 22:58:43 volumio volumio[829]: 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
Dec 06 22:58:43 volumio volumio[829]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1)
Dec 06 22:58:43 volumio volumio[829]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1)
Dec 06 22:58:43 volumio volumio[829]: info: Building wheels for collected packages: camilladsp
Dec 06 22:58:43 volumio volumio[829]: info: Running setup.py bdist_wheel for camilladsp: started
Dec 06 22:58:44 volumio volumio[829]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done'
Dec 06 22:58:44 volumio volumio[829]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-wi0el1tt/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6
Dec 06 22:58:44 volumio volumio[829]: info: Successfully built camilladsp
Dec 06 22:58:48 volumio volumio[829]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple
Dec 06 22:58:48 volumio volumio[829]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2
Dec 06 22:58:48 volumio volumio[829]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-6zcxa_tx
Dec 06 22:58:49 volumio volumio[829]: 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
Dec 06 22:58:49 volumio volumio[829]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1)
Dec 06 22:58:49 volumio volumio[829]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3)
Dec 06 22:58:50 volumio volumio[829]: 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)
Dec 06 22:58:50 volumio volumio[829]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0)
Dec 06 22:58:50 volumio volumio[829]: 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)
Dec 06 22:58:50 volumio volumio[829]: 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)
Dec 06 22:58:50 volumio volumio[829]: 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)
Dec 06 22:58:50 volumio volumio[829]: 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)
Dec 06 22:58:50 volumio volumio[829]: info: Requirement already satisfied: zipp>=0.5 in /usr/local/lib/python3.7/dist-packages (from importlib-metadata; python_version < "3.8"->jsonschema->camilladsp-plot==1.0.2) (3.15.0)
Dec 06 22:58:50 volumio volumio[829]: info: Building wheels for collected packages: camilladsp-plot
Dec 06 22:58:50 volumio volumio[829]: info: Running setup.py bdist_wheel for camilladsp-plot: started
Dec 06 22:58:51 volumio volumio[829]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done'
Dec 06 22:58:51 volumio volumio[829]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-2e2_sm2i/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9
Dec 06 22:58:51 volumio volumio[829]: info: Successfully built camilladsp-plot
Dec 06 22:58:51 volumio volumio[829]: info: copying hw detection script
Dec 06 22:58:51 volumio volumio[829]: info: Detected cpu architecture as armv7l
Dec 06 22:58:52 volumio sudo[20945]: 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
Dec 06 22:58:52 volumio sudo[20945]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:52 volumio sudo[20945]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:52 volumio sudo[20947]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params
Dec 06 22:58:52 volumio sudo[20947]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:52 volumio sudo[20947]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:52 volumio sudo[20949]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc
Dec 06 22:58:52 volumio sudo[20949]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:58:53 volumio volumio[829]: info: Reading package lists...
Dec 06 22:58:53 volumio volumio[829]: info: Building dependency tree...
Dec 06 22:58:53 volumio volumio[829]: info: Reading state information...
Dec 06 22:58:54 volumio volumio[829]: info: drc is already the newest version (3.2.2~dfsg0-2).
Dec 06 22:58:54 volumio sudo[20949]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:54 volumio sudo[20541]: pam_unix(sudo:session): session closed for user root
Dec 06 22:58:54 volumio volumio[829]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded.
Dec 06 22:58:54 volumio volumio[829]: info: Install script completed
Dec 06 22:58:54 volumio volumio[829]: info: Done installing plugin.
Dec 06 22:58:54 volumio volumio[829]: info: Enabling plugin fusiondsp
Dec 06 22:58:54 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 06 22:58:54 volumio volumio[829]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.62","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"Calm Radio","name":"calmradio","category":"music_service","version":"1.2.4","icon":"fa-heartbeat","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.13","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"GPIO Buttons","name":"gpio-buttons","category":"system_hardware","version":"0.8.4","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"GPIO Control","name":"gpio_control","category":"system_hardware","version":"1.0.10","icon":"fa-microchip","isManuallyInstalled":false,"enabled":true,"active":true}]
Dec 06 22:58:54 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 06 22:58:54 volumio volumio[829]: info: Folder /tmp/plugins removed
Dec 06 22:58:54 volumio volumio[829]: info: Folder /tmp/downloaded_plugin.zip removed
Dec 06 22:58:54 volumio volumio[829]: Plugin install end detected on script
Dec 06 22:58:54 volumio volumio[829]: info: Folder /data/temp removed
Dec 06 22:59:00 volumio go-librespot[1204]: time="2025-12-06T22:59:00+01:00" level=trace msg="sent dealer ping"
Dec 06 22:59:00 volumio go-librespot[1204]: time="2025-12-06T22:59:00+01:00" level=trace msg="received dealer pong"
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::servicePause
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePause
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pause
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand pause
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand pause took 3 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":249490,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2887 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":249490,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2887 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":249490,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2887 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":249490,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2887 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::stPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 32ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 29ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 28ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 29ms
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Status changed: pause
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Handling event: musicPause
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Status changed: pause
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Status changed: pause
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Status changed: pause
Dec 06 22:59:07 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:07 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:07 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:07 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPlay
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::play index undefined
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::resume
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - ---- read samplerate from file: 96000
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - ---- read samplerate from file: 96000
Dec 06 22:59:07 volumio volumio[829]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand play took 33 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info:
Dec 06 22:59:07 volumio volumio[829]: ---------------------------- MPD announces state update: player
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::getState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 4 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 3 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 2 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand status took 1 milliseconds
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseState
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Dec 06 22:59:07 volumio volumio[829]: error: FusionDsp - WebSocket error: [object Object]
Dec 06 22:59:07 volumio volumio[829]: error: FusionDsp - WebSocket error: [object Object]
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 3 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds
Dec 06 22:59:07 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250361,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2802 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService play
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus pause
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250361,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2802 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService play
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play
Dec 06 22:59:07 volumio volumio[829]: info: Received an update from plugin. extracting info from payload
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250361,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2802 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService play
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play
Dec 06 22:59:07 volumio volumio[829]: info: Received an update from plugin. extracting info from payload
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: ControllerMpd::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::servicePushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250361,"duration":369,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2802 Kbps","isStreaming":false,"title":"Rose","artist":"Manu Katché","album":"Neighbourhood","uri":"USB/DISKMUSIC/Manu Katche/Manu Katche - Neighborhood/10 Rose.flac","trackType":"flac"}
Dec 06 22:59:07 volumio volumio[829]: verbose: CURRENT POSITION 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState stateService play
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play
Dec 06 22:59:07 volumio volumio[829]: info: Received an update from plugin. extracting info from payload
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: CoreStateMachine::pushState
Dec 06 22:59:07 volumio volumio[829]: info: CorePlayQueue::getTrack 9
Dec 06 22:59:07 volumio volumio[829]: info: CoreCommandRouter::volumioPushState
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 33ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 32ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 33ms
Dec 06 22:59:07 volumio volumio[829]: info: ------------------------------ 32ms
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:07 volumio volumio[829]: info: [GPIO_Control] Handling event: musicPlay
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: info: [GPIO_Control] Status changed: play
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:08 volumio volumio[829]: SPOTIFY: RECEIVED VOLUMIO VOLUME 70
Dec 06 22:59:18 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 06 22:59:18 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 06 22:59:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 06 22:59:30 volumio go-librespot[1204]: time="2025-12-06T22:59:30+01:00" level=trace msg="sent dealer ping"
Dec 06 22:59:30 volumio go-librespot[1204]: time="2025-12-06T22:59:30+01:00" level=trace msg="received dealer pong"
Dec 06 22:59:32 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Dec 06 22:59:43 volumio volumio[829]: info: Enabling plugin fusiondsp
Dec 06 22:59:43 volumio volumio[829]: info: Loading plugin "fusiondsp"...
Dec 06 22:59:43 volumio volumio[829]: info: Applying required configuration parameters for plugin fusiondsp
Dec 06 22:59:43 volumio volumio[829]: info: Preparing to generate the ALSA configuration file
Dec 06 22:59:43 volumio volumio[829]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Dec 06 22:59:43 volumio volumio[829]: info: Reading ALSA contributions from plugins.
Dec 06 22:59:43 volumio volumio[829]: info: Asound.conf file unchanged, so no further update is needed
Dec 06 22:59:43 volumio volumio[829]: info: Output device has changed, restarting MPD
Dec 06 22:59:43 volumio sudo[21178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 06 22:59:43 volumio sudo[21178]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio volumio[829]: info: Output device has changed, restarting Shairport Sync
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 22:59:43 volumio sudo[21178]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:43 volumio sudo[21181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 06 22:59:43 volumio sudo[21181]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio systemd[1]: Stopping Music Player Daemon...
Dec 06 22:59:43 volumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 06 22:59:43 volumio volumio[829]: info: PLUGIN START: fusiondsp
Dec 06 22:59:43 volumio volumio[829]: info: Loading i18n strings for locale en
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: FusionDsp - mixtype--------------------- Hardware
Dec 06 22:59:43 volumio volumio[829]: info: Preparing to generate the ALSA configuration file
Dec 06 22:59:43 volumio volumio[829]: info: Done.
Dec 06 22:59:43 volumio volumio[829]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Dec 06 22:59:43 volumio volumio[829]: info: Reading ALSA contributions from plugins.
Dec 06 22:59:43 volumio volumio[829]: info: MPD Permissions set
Dec 06 22:59:43 volumio volumio[829]: info: Asound.conf file unchanged, so no further update is needed
Dec 06 22:59:43 volumio volumio[829]: info: Output device has changed, restarting MPD
Dec 06 22:59:43 volumio sudo[21187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Dec 06 22:59:43 volumio sudo[21187]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio volumio[829]: info: Output device has changed, restarting Shairport Sync
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 22:59:43 volumio sudo[21187]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:43 volumio sudo[21190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Dec 06 22:59:43 volumio sudo[21190]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio volumio[829]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 06 22:59:43 volumio volumio[829]: info: MPD Permissions set
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 06 22:59:43 volumio volumio[829]: info: Starting Shairport Sync
Dec 06 22:59:43 volumio volumio[829]: error: Upnp client error: Error: This socket has been ended by the other party
Dec 06 22:59:43 volumio volumio[829]: info: Starting Shairport Sync
Dec 06 22:59:43 volumio sudo[21204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 06 22:59:43 volumio sudo[21204]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio sudo[21207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Dec 06 22:59:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Dec 06 22:59:43 volumio sudo[21207]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:43 volumio systemd[1]: shairport-sync.service: Succeeded.
Dec 06 22:59:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Dec 06 22:59:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Dec 06 22:59:43 volumio sudo[21204]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:43 volumio sudo[21207]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:43 volumio volumio[829]: info: Shairport-Sync Started
Dec 06 22:59:43 volumio volumio[829]: info: Shairport-Sync Started
Dec 06 22:59:44 volumio systemd[1]: mpd.service: Succeeded.
Dec 06 22:59:44 volumio systemd[1]: Stopped Music Player Daemon.
Dec 06 22:59:44 volumio systemd[1]: Starting Music Player Daemon...
Dec 06 22:59:44 volumio sudo[21212]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Dec 06 22:59:44 volumio sudo[21212]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 06 22:59:44 volumio sudo[21212]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:45 volumio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 22:59:45 volumio volumio[829]: TypeError: Cannot read property 'off' of undefined
Dec 06 22:59:45 volumio volumio[829]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15)
Dec 06 22:59:45 volumio volumio[829]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30)
Dec 06 22:59:45 volumio volumio[829]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8)
Dec 06 22:59:45 volumio volumio[829]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29)
Dec 06 22:59:45 volumio volumio[829]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1412:47)
Dec 06 22:59:45 volumio volumio[829]: at Socket.emit (events.js:315:20)
Dec 06 22:59:45 volumio volumio[829]: at /volumio/node_modules/socket.io/lib/socket.js:528:12
Dec 06 22:59:45 volumio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:75:11)
Dec 06 22:59:45 volumio volumio[829]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Dec 06 22:59:45 volumio mpd[21215]: Dec 06 22:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Dec 06 22:59:45 volumio systemd[1]: Started Music Player Daemon.
Dec 06 22:59:45 volumio sudo[21190]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:45 volumio sudo[21181]: pam_unix(sudo:session): session closed for user root
Dec 06 22:59:46 volumio sudo[21228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-06 22:58
Dec 06 22:59:46 volumio sudo[21228]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET"
VOLUMIO_VERSION="3.874"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"