-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-09-02 21:34:57 CEST. -- Sep 02 21:33:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 21:33:01 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 21:33:01 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:33:01 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:33:20 volumio volumio[901]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 02 21:33:22 volumio volumio[901]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Sep 02 21:33:22 volumio go-librespot[2319]: time="2025-09-02T21:33:22+02:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Sep 02 21:33:22 volumio volumio[901]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Sep 02 21:33:22 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 21:33:22 volumio volumio[901]: Cannot find translation for source YouTube2 Sep 02 21:33:22 volumio volumio[901]: Cannot find translation for source Radio Paradise Sep 02 21:33:22 volumio sudo[2827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Sep 02 21:33:22 volumio sudo[2827]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:33:22 volumio systemd[1]: Stopping go-librespot Daemon... Sep 02 21:33:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Sep 02 21:33:22 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Sep 02 21:33:22 volumio systemd[1]: Stopped go-librespot Daemon. Sep 02 21:33:23 volumio sudo[2827]: pam_unix(sudo:session): session closed for user root Sep 02 21:33:23 volumio volumio[901]: info: Connection to go-librespot Websocket closed Sep 02 21:33:23 volumio volumio[901]: info: Connection to go-librespot Websocket closed Sep 02 21:33:23 volumio volumio[901]: info: Check plugin dependencies Sep 02 21:33:23 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 21:33:23 volumio volumio[901]: info: Rename folder Sep 02 21:33:23 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Sep 02 21:33:23 volumio volumio[901]: info: Move to category Sep 02 21:33:26 volumio volumio[901]: info: Checking if install.sh is present Sep 02 21:33:26 volumio volumio[901]: info: Executing install.sh Sep 02 21:33:26 volumio sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Sep 02 21:33:26 volumio sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:33:26 volumio volumio[901]: info: Installing Go-librespot Sep 02 21:33:26 volumio volumio[901]: info: Checking old vollibrespot installs Sep 02 21:33:26 volumio systemd[1]: Reloading. Sep 02 21:33:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 21:33:27 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Sep 02 21:33:27 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Sep 02 21:33:27 volumio volumio[901]: info: Downloading daemon Sep 02 21:33:31 volumio volumio[901]: info: Creating directories Sep 02 21:33:31 volumio volumio[901]: info: Creating data directory Sep 02 21:33:31 volumio volumio[901]: info: Creating Start Script Sep 02 21:33:31 volumio systemd[1]: Reloading. Sep 02 21:33:32 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Sep 02 21:33:32 volumio systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid → /run/rpc.statd.pid; please update the unit file accordingly. Sep 02 21:33:32 volumio volumio[901]: Plugin install end detected on script Sep 02 21:33:32 volumio sudo[2847]: pam_unix(sudo:session): session closed for user root Sep 02 21:33:32 volumio volumio[901]: info: Install script completed Sep 02 21:33:32 volumio systemd[1]: Starting Daily apt download activities... Sep 02 21:33:32 volumio volumio[901]: info: Done installing plugin. Sep 02 21:33:32 volumio volumio[901]: info: Enabling plugin spop Sep 02 21:33:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 21:33:32 volumio volumio[901]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.54","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.5","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 02 21:33:32 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 21:33:32 volumio volumio[901]: info: Folder /tmp/plugins removed Sep 02 21:33:32 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Sep 02 21:33:32 volumio volumio[901]: info: Folder /data/temp removed Sep 02 21:33:32 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:33:33 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:33:37 volumio volumio[901]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Sep 02 21:33:39 volumio systemd[1]: apt-daily.service: Succeeded. Sep 02 21:33:39 volumio systemd[1]: Started Daily apt download activities. Sep 02 21:33:41 volumio volumio[901]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Sep 02 21:33:41 volumio volumio[901]: info: FusionDsp - Stopping FusionDsp service Sep 02 21:33:41 volumio volumio[901]: info: camilladsp stopping service pid 2623... Sep 02 21:33:41 volumio volumio[901]: info: camilladsp service terminated, instance 1 Sep 02 21:33:41 volumio sudo[3011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Sep 02 21:33:41 volumio sudo[3011]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:33:41 volumio systemd[1]: Stopping FusionDsp Daemon... Sep 02 21:33:41 volumio volumio[1229]: Backend configuration: Sep 02 21:33:41 volumio volumio[1229]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 02 21:33:41 volumio volumio[1229]: active_config_txt: null Sep 02 21:33:41 volumio volumio[1229]: camilla_host: 127.0.0.1 Sep 02 21:33:41 volumio volumio[1229]: camilla_port: 9876 Sep 02 21:33:41 volumio volumio[1229]: coeff_dir: /data/INTERNAL/FusionDsp/filters Sep 02 21:33:41 volumio volumio[1229]: config_dir: /data/configuration/audio_interface/fusiondsp Sep 02 21:33:41 volumio volumio[1229]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Sep 02 21:33:41 volumio volumio[1229]: log_file: /tmp/camilladsp.log Sep 02 21:33:41 volumio volumio[1229]: on_get_active_config: null Sep 02 21:33:41 volumio volumio[1229]: on_set_active_config: null Sep 02 21:33:41 volumio volumio[1229]: port: 5011 Sep 02 21:33:41 volumio volumio[1229]: supported_capture_types: null Sep 02 21:33:41 volumio volumio[1229]: supported_playback_types: null Sep 02 21:33:41 volumio volumio[1229]: update_config_symlink: false Sep 02 21:33:41 volumio volumio[1229]: update_config_txt: false Sep 02 21:33:41 volumio volumio[1229]: ======== Running on http://0.0.0.0:5011 ======== Sep 02 21:33:41 volumio volumio[1229]: (Press CTRL+C to quit) Sep 02 21:33:41 volumio systemd[1]: fusiondsp.service: Succeeded. Sep 02 21:33:41 volumio systemd[1]: Stopped FusionDsp Daemon. Sep 02 21:33:41 volumio sudo[3011]: pam_unix(sudo:session): session closed for user root Sep 02 21:33:41 volumio volumio[901]: info: FusionDsp - Reporting Fusion DSP Disabled Sep 02 21:33:41 volumio volumio[901]: info: Removing fusiondspeq DSP Signal Path Element Sep 02 21:33:41 volumio volumio[901]: info: Check plugin dependencies Sep 02 21:33:41 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 21:33:41 volumio volumio[901]: info: Rename folder Sep 02 21:33:41 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Sep 02 21:33:41 volumio volumio[901]: info: Move to category Sep 02 21:33:44 volumio volumio[901]: info: Checking if install.sh is present Sep 02 21:33:44 volumio volumio[901]: info: Executing install.sh Sep 02 21:33:44 volumio sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Sep 02 21:33:44 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:33:44 volumio volumio[901]: info: creating filters folder and copying demo filters Sep 02 21:33:44 volumio volumio[901]: info: copying demo flters Sep 02 21:33:44 volumio volumio[901]: info: ./presets/ Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/ Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/.Bass-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/.Classic-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/.Voice-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/2XEQ15/.Rock-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/PEQ/ Sep 02 21:33:44 volumio volumio[901]: info: ./presets/convfir/ Sep 02 21:33:44 volumio volumio[901]: info: ./presets/convfir/kef.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/convfir/test.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/ Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/.Bass-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/.Classic-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/.Voice-FusionDsp.json Sep 02 21:33:44 volumio volumio[901]: info: ./presets/EQ15/.Rock-FusionDsp.json Sep 02 21:33:44 volumio sudo[3057]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update Sep 02 21:33:44 volumio sudo[3057]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:33:44 volumio volumio[901]: info: Installing/fusiondsp dependencies Sep 02 21:33:45 volumio volumio[901]: info: Get:1 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] Sep 02 21:33:45 volumio volumio[901]: info: Get:2 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Sep 02 21:33:45 volumio volumio[901]: info: Hit:3 https://deb.nodesource.com/node_14.x buster InRelease Sep 02 21:33:46 volumio volumio[901]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] Sep 02 21:33:46 volumio volumio[901]: info: Fetched 470 kB in 1s (327 kB/s) Sep 02 21:33:53 volumio volumio[901]: info: Reading package lists... Sep 02 21:33:55 volumio volumio[901]: info: Building dependency tree... Sep 02 21:33:55 volumio volumio[901]: info: Reading state information... Sep 02 21:33:55 volumio volumio[901]: info: 114 packages can be upgraded. Run 'apt list --upgradable' to see them. Sep 02 21:33:55 volumio sudo[3057]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:00 volumio sudo[3296]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Sep 02 21:34:00 volumio sudo[3296]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:00 volumio volumio[901]: info: Reading package lists... Sep 02 21:34:01 volumio volumio[901]: info: Building dependency tree... Sep 02 21:34:01 volumio volumio[901]: info: Reading state information... Sep 02 21:34:03 volumio volumio[901]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). Sep 02 21:34:03 volumio sudo[3296]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:03 volumio volumio[901]: info: python3-pip is already the newest version (18.1-5+rpt1). Sep 02 21:34:03 volumio volumio[901]: info: 0 upgraded, 0 newly installed, 0 to remove and 115 not upgraded. Sep 02 21:34:03 volumio sudo[3334]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Sep 02 21:34:03 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:03 volumio volumio[901]: info: lib/systemd/system/fusiondsp.service Sep 02 21:34:03 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:06 volumio volumio[901]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Sep 02 21:34:06 volumio volumio[901]: info: more info at http://www.winimage.com/zLibDll/unzip.html Sep 02 21:34:06 volumio volumio[901]: info: Sep 02 21:34:06 volumio volumio[901]: info: cgui-1.0.0.zip opened Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/LICENSE.txt Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/README.md Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/build/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/robots.txt Sep 02 21:34:06 volumio sudo[3345]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/logo512.png Sep 02 21:34:06 volumio sudo[3345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/asset-manifest.json Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/index.html Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/.put_statics_here Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/favicon.ico Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/logo192.png Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/manifest.json Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/css-variables.css Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/service-worker.js Sep 02 21:34:06 volumio sudo[3345]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/build/static/ Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/build/static/css/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map Sep 02 21:34:06 volumio sudo[3347]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map Sep 02 21:34:06 volumio sudo[3347]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/main.1503f6cd.css Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/build/static/media/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/build/static/js/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.71e11955.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js Sep 02 21:34:06 volumio sudo[3347]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.71e11955.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/config/ Sep 02 21:34:06 volumio sudo[3349]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/config/camillagui.yml Sep 02 21:34:06 volumio sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/config/gui-config.yml Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/backend/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/filters_test.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/version.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/filters.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/settings.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/views.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/filterdefaults.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__init__.py Sep 02 21:34:06 volumio volumio[901]: info: creating directory: cgui/backend/__pycache__/ Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc Sep 02 21:34:06 volumio sudo[3349]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/routes.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/backend/filemanagement.py Sep 02 21:34:06 volumio volumio[901]: info: extracting: cgui/main.py Sep 02 21:34:15 volumio volumio[901]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Sep 02 21:34:15 volumio volumio[901]: info: Collecting git+https://github.com/HEnquist/pycamilladsp.git@v1.0.0 Sep 02 21:34:15 volumio volumio[901]: info: Cloning https://github.com/HEnquist/pycamilladsp.git (to revision v1.0.0) to /tmp/pip-req-build-348k_iwg Sep 02 21:34:19 volumio volumio[901]: 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 Sep 02 21:34:19 volumio volumio[901]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (6.0.1) Sep 02 21:34:19 volumio volumio[901]: info: Requirement already satisfied: websocket_client in /usr/local/lib/python3.7/dist-packages (from camilladsp==1.0.0) (1.6.1) Sep 02 21:34:19 volumio volumio[901]: info: Building wheels for collected packages: camilladsp Sep 02 21:34:19 volumio volumio[901]: info: Running setup.py bdist_wheel for camilladsp: started Sep 02 21:34:20 volumio volumio[901]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Sep 02 21:34:20 volumio volumio[901]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-1kgxbcrv/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Sep 02 21:34:20 volumio volumio[901]: info: Successfully built camilladsp Sep 02 21:34:27 volumio volumio[901]: info: Looking in indexes: https://pypi.org/simple, https://www.piwheels.org/simple Sep 02 21:34:28 volumio volumio[901]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Sep 02 21:34:28 volumio volumio[901]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-jwif2dzn Sep 02 21:34:30 volumio volumio[901]: 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 Sep 02 21:34:30 volumio volumio[901]: info: Requirement already satisfied: PyYAML in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (6.0.1) Sep 02 21:34:30 volumio volumio[901]: info: Requirement already satisfied: jsonschema in /usr/local/lib/python3.7/dist-packages (from camilladsp-plot==1.0.2) (4.17.3) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:30 volumio volumio[901]: info: Requirement already satisfied: attrs>=17.4.0 in /usr/lib/python3/dist-packages (from jsonschema->camilladsp-plot==1.0.2) (18.2.0) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:30 volumio volumio[901]: 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) Sep 02 21:34:31 volumio volumio[901]: info: Building wheels for collected packages: camilladsp-plot Sep 02 21:34:31 volumio volumio[901]: info: Running setup.py bdist_wheel for camilladsp-plot: started Sep 02 21:34:32 volumio volumio[901]: info: Running setup.py bdist_wheel for camilladsp-plot: finished with status 'done' Sep 02 21:34:32 volumio volumio[901]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-6s159v5i/wheels/11/e1/43/8275af2f537d569c7097d202b4955f9aec629859be39dab2e9 Sep 02 21:34:32 volumio volumio[901]: info: Successfully built camilladsp-plot Sep 02 21:34:33 volumio volumio[901]: info: copying hw detection script Sep 02 21:34:33 volumio volumio[901]: info: Detected cpu architecture as armv7l Sep 02 21:34:35 volumio sudo[3550]: 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 Sep 02 21:34:35 volumio sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:35 volumio sudo[3550]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:35 volumio sudo[3552]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Sep 02 21:34:35 volumio sudo[3552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:35 volumio sudo[3552]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:35 volumio sudo[3554]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Sep 02 21:34:35 volumio sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:35 volumio volumio[901]: info: Reading package lists... Sep 02 21:34:36 volumio volumio[901]: info: Building dependency tree... Sep 02 21:34:36 volumio volumio[901]: info: Reading state information... Sep 02 21:34:37 volumio volumio[901]: info: drc is already the newest version (3.2.2~dfsg0-2). Sep 02 21:34:37 volumio sudo[3554]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:37 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:37 volumio volumio[901]: info: 0 upgraded, 0 newly installed, 0 to remove and 115 not upgraded. Sep 02 21:34:37 volumio volumio[901]: info: Install script completed Sep 02 21:34:37 volumio volumio[901]: info: Done installing plugin. Sep 02 21:34:37 volumio volumio[901]: info: Enabling plugin fusiondsp Sep 02 21:34:37 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 21:34:37 volumio volumio[901]: info: [{"prettyName":"FusionDsp","name":"fusiondsp","category":"audio_interface","version":"1.0.61","icon":"fa-sliders fa-rotate-90","isManuallyInstalled":true,"enabled":true,"active":false},{"prettyName":"Radio Paradise","name":"radio_paradise","category":"music_service","version":"1.0.3","icon":"fa-headphones","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.2.2","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":false},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.1.5","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.6","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 02 21:34:37 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 21:34:37 volumio volumio[901]: info: Folder /tmp/plugins removed Sep 02 21:34:37 volumio volumio[901]: info: Folder /tmp/downloaded_plugin.zip removed Sep 02 21:34:37 volumio volumio[901]: Plugin install end detected on script Sep 02 21:34:37 volumio volumio[901]: info: Folder /data/temp removed Sep 02 21:34:37 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:34:37 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::volumioPause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::servicePause Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::servicePause Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::pause Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand pause Sep 02 21:34:43 volumio volumio[901]: info: Sep 02 21:34:43 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand pause took 6 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:43 volumio volumio[901]: info: Sep 02 21:34:43 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:43 volumio volumio[901]: info: Sep 02 21:34:43 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:43 volumio volumio[901]: info: Sep 02 21:34:43 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand status took 7 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand status took 6 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 21:34:43 volumio volumio[901]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:43 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61362,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1737 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:43 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61362,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1737 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:43 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61362,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1737 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:43 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Sep 02 21:34:43 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61362,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1737 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:43 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState stateService pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:43 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:43 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:43 volumio volumio[901]: info: CoreStateMachine::stPlaybackTimer Sep 02 21:34:43 volumio volumio[901]: info: ------------------------------ 103ms Sep 02 21:34:43 volumio volumio[901]: info: ------------------------------ 99ms Sep 02 21:34:43 volumio volumio[901]: info: ------------------------------ 98ms Sep 02 21:34:43 volumio volumio[901]: info: ------------------------------ 97ms Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPlay Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::play index undefined Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::startPlaybackTimer Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::resume Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand play Sep 02 21:34:44 volumio volumio[901]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Sep 02 21:34:44 volumio volumio[901]: info: FusionDsp - ---- read samplerate from file: 48000 Sep 02 21:34:44 volumio volumio[901]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 02 21:34:44 volumio volumio[901]: info: FusionDsp - Effects disabled Sep 02 21:34:44 volumio volumio[901]: info: Sep 02 21:34:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand play took 43 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:44 volumio volumio[901]: info: Sep 02 21:34:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:44 volumio volumio[901]: info: Sep 02 21:34:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:44 volumio volumio[901]: info: Sep 02 21:34:44 volumio volumio[901]: ---------------------------- MPD announces state update: player Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::getState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand status Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand status took 6 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand status took 6 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand status took 4 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand status took 3 milliseconds Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseState Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 21:34:44 volumio volumio[901]: error: FusionDsp - WebSocket error: [object Object] Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 02 21:34:44 volumio volumio[901]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:44 volumio volumio[901]: verbose: ControllerMpd::parseTrackInfo Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62233,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1314 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus pause Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62233,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1314 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Sep 02 21:34:44 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62233,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1314 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Sep 02 21:34:44 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: ControllerMpd::pushState Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::servicePushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: verbose: STATE SERVICE {"status":"play","position":0,"seek":62233,"duration":189,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1314 Kbps","isStreaming":false,"title":"amen","artist":"Alana Springsteen","album":"TWENTY SOMETHING","uri":"NAS/audio/Alana sprinsteen/18 amen.flac","trackType":"flac"} Sep 02 21:34:44 volumio volumio[901]: verbose: CURRENT POSITION 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState stateService play Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::syncState currentStatus play Sep 02 21:34:44 volumio volumio[901]: info: Received an update from plugin. extracting info from payload Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: CoreStateMachine::pushState Sep 02 21:34:44 volumio volumio[901]: info: CorePlayQueue::getTrack 0 Sep 02 21:34:44 volumio volumio[901]: info: CoreCommandRouter::volumioPushState Sep 02 21:34:44 volumio volumio[901]: info: ------------------------------ 96ms Sep 02 21:34:44 volumio volumio[901]: info: ------------------------------ 95ms Sep 02 21:34:44 volumio volumio[901]: info: ------------------------------ 94ms Sep 02 21:34:44 volumio volumio[901]: info: ------------------------------ 93ms Sep 02 21:34:55 volumio volumio[901]: info: Enabling plugin fusiondsp Sep 02 21:34:55 volumio volumio[901]: info: Loading plugin "fusiondsp"... Sep 02 21:34:55 volumio volumio[901]: info: Applying required configuration parameters for plugin fusiondsp Sep 02 21:34:55 volumio volumio[901]: info: Preparing to generate the ALSA configuration file Sep 02 21:34:55 volumio volumio[901]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 21:34:55 volumio volumio[901]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 21:34:55 volumio volumio[901]: info: Reading ALSA contributions from plugins. Sep 02 21:34:55 volumio volumio[901]: info: Asound.conf file unchanged, so no further update is needed Sep 02 21:34:55 volumio volumio[901]: info: Output device has changed, restarting MPD Sep 02 21:34:55 volumio sudo[3635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 21:34:55 volumio sudo[3635]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:55 volumio volumio[901]: info: Output device has changed, restarting Shairport Sync Sep 02 21:34:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:55 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 21:34:55 volumio sudo[3635]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:55 volumio sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 21:34:55 volumio sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:55 volumio systemd[1]: Stopping Music Player Daemon... Sep 02 21:34:56 volumio systemd[1]: mpd.service: Succeeded. Sep 02 21:34:56 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 21:34:56 volumio volumio[901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 21:34:56 volumio volumio[901]: info: PLUGIN START: fusiondsp Sep 02 21:34:56 volumio systemd[1]: Starting Music Player Daemon... Sep 02 21:34:56 volumio volumio[901]: info: Loading i18n strings for locale nl Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: FusionDsp - mixtype--------------------- Software Sep 02 21:34:56 volumio volumio[901]: info: Preparing to generate the ALSA configuration file Sep 02 21:34:56 volumio volumio[901]: info: Done. Sep 02 21:34:56 volumio volumio[901]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Sep 02 21:34:56 volumio volumio[901]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 21:34:56 volumio volumio[901]: info: Reading ALSA contributions from plugins. Sep 02 21:34:56 volumio volumio[901]: info: MPD Permissions set Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 21:34:56 volumio volumio[901]: info: Starting Shairport Sync Sep 02 21:34:56 volumio volumio[901]: info: Asound.conf file unchanged, so no further update is needed Sep 02 21:34:56 volumio volumio[901]: info: Output device has changed, restarting MPD Sep 02 21:34:56 volumio sudo[3643]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 21:34:56 volumio sudo[3643]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:56 volumio sudo[3643]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:56 volumio sudo[3650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 02 21:34:56 volumio sudo[3650]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:56 volumio sudo[3654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 02 21:34:56 volumio volumio[901]: info: Output device has changed, restarting Shairport Sync Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 21:34:56 volumio sudo[3654]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:56 volumio sudo[3654]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:56 volumio sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 02 21:34:56 volumio sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:56 volumio volumio[901]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 02 21:34:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 02 21:34:56 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 02 21:34:56 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 02 21:34:56 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 02 21:34:56 volumio systemd[1]: mpd.service: Succeeded. Sep 02 21:34:56 volumio systemd[1]: Stopped Music Player Daemon. Sep 02 21:34:56 volumio volumio[901]: info: MPD Permissions set Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio systemd[1]: Starting Music Player Daemon... Sep 02 21:34:56 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 02 21:34:56 volumio sudo[3650]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 21:34:56 volumio volumio[901]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 21:34:56 volumio volumio[901]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 21:34:56 volumio volumio[901]: TypeError: Cannot read property 'off' of undefined Sep 02 21:34:56 volumio volumio[901]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Sep 02 21:34:56 volumio volumio[901]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Sep 02 21:34:56 volumio volumio[901]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Sep 02 21:34:56 volumio volumio[901]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Sep 02 21:34:56 volumio volumio[901]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Sep 02 21:34:56 volumio volumio[901]: at Socket.emit (events.js:315:20) Sep 02 21:34:56 volumio volumio[901]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 02 21:34:56 volumio volumio[901]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Sep 02 21:34:56 volumio volumio[901]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 21:34:56 volumio sudo[3663]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 02 21:34:56 volumio sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 21:34:56 volumio sudo[3663]: pam_unix(sudo:session): session closed for user root Sep 02 21:34:57 volumio sudo[3682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 21:33 Sep 02 21:34:57 volumio sudo[3682]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"