-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-05-11 12:39:43 CEST. -- May 11 12:38:00 kueche volumio[879]: info: Selecting previously unselected package libstdc++-8-dev:armhf. May 11 12:38:00 kueche volumio[879]: info: Preparing to unpack .../12-libstdc++-8-dev_8.3.0-6+rpi1_armhf.deb ... May 11 12:38:00 kueche volumio[879]: info: Unpacking libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... May 11 12:38:02 kueche volumio[879]: info: Selecting previously unselected package g++-8. May 11 12:38:02 kueche volumio[879]: info: Preparing to unpack .../13-g++-8_8.3.0-6+rpi1_armhf.deb ... May 11 12:38:02 kueche volumio[879]: info: Unpacking g++-8 (8.3.0-6+rpi1) ... May 11 12:38:05 kueche volumio[879]: info: Selecting previously unselected package g++. May 11 12:38:05 kueche volumio[879]: info: Preparing to unpack .../14-g++_4%3a8.3.0-1+rpi2_armhf.deb ... May 11 12:38:05 kueche volumio[879]: info: Unpacking g++ (4:8.3.0-1+rpi2) ... May 11 12:38:05 kueche volumio[879]: info: Selecting previously unselected package make. May 11 12:38:05 kueche volumio[879]: info: Preparing to unpack .../15-make_4.2.1-1.2_armhf.deb ... May 11 12:38:05 kueche volumio[879]: info: Unpacking make (4.2.1-1.2) ... May 11 12:38:06 kueche volumio[879]: info: Selecting previously unselected package patch. May 11 12:38:06 kueche volumio[879]: info: Preparing to unpack .../16-patch_2.7.6-3+deb10u1_armhf.deb ... May 11 12:38:06 kueche volumio[879]: info: Unpacking patch (2.7.6-3+deb10u1) ... May 11 12:38:06 kueche volumio[879]: info: Selecting previously unselected package dpkg-dev. May 11 12:38:06 kueche volumio[879]: info: Preparing to unpack .../17-dpkg-dev_1.19.8_all.deb ... May 11 12:38:06 kueche volumio[879]: info: Unpacking dpkg-dev (1.19.8) ... May 11 12:38:08 kueche volumio[879]: info: Selecting previously unselected package build-essential. May 11 12:38:08 kueche volumio[879]: info: Preparing to unpack .../18-build-essential_12.6_armhf.deb ... May 11 12:38:08 kueche volumio[879]: info: Unpacking build-essential (12.6) ... May 11 12:38:08 kueche volumio[879]: info: Selecting previously unselected package dh-python. May 11 12:38:08 kueche volumio[879]: info: Preparing to unpack .../19-dh-python_3.20190308_all.deb ... May 11 12:38:08 kueche volumio[879]: info: Unpacking dh-python (3.20190308) ... May 11 12:38:09 kueche volumio[879]: info: Selecting previously unselected package libfakeroot:armhf. May 11 12:38:09 kueche volumio[879]: info: Preparing to unpack .../20-libfakeroot_1.23-1_armhf.deb ... May 11 12:38:09 kueche volumio[879]: info: Unpacking libfakeroot:armhf (1.23-1) ... May 11 12:38:09 kueche volumio[879]: info: Selecting previously unselected package fakeroot. May 11 12:38:09 kueche volumio[879]: info: Preparing to unpack .../21-fakeroot_1.23-1_armhf.deb ... May 11 12:38:09 kueche volumio[879]: info: Unpacking fakeroot (1.23-1) ... May 11 12:38:09 kueche volumio[879]: info: Selecting previously unselected package libalgorithm-diff-perl. May 11 12:38:09 kueche volumio[879]: info: Preparing to unpack .../22-libalgorithm-diff-perl_1.19.03-2_all.deb ... May 11 12:38:09 kueche volumio[879]: info: Unpacking libalgorithm-diff-perl (1.19.03-2) ... May 11 12:38:10 kueche volumio[879]: info: Selecting previously unselected package libalgorithm-diff-xs-perl. May 11 12:38:10 kueche volumio[879]: info: Preparing to unpack .../23-libalgorithm-diff-xs-perl_0.04-5+b1_armhf.deb ... May 11 12:38:10 kueche volumio[879]: info: Unpacking libalgorithm-diff-xs-perl (0.04-5+b1) ... May 11 12:38:11 kueche volumio[879]: info: Selecting previously unselected package libalgorithm-merge-perl. May 11 12:38:11 kueche volumio[879]: info: Preparing to unpack .../24-libalgorithm-merge-perl_0.08-3_all.deb ... May 11 12:38:11 kueche volumio[879]: info: Unpacking libalgorithm-merge-perl (0.08-3) ... May 11 12:38:11 kueche volumio[879]: info: Selecting previously unselected package libexpat1-dev:armhf. May 11 12:38:11 kueche volumio[879]: info: Preparing to unpack .../25-libexpat1-dev_2.2.6-2+deb10u7_armhf.deb ... May 11 12:38:11 kueche volumio[879]: info: Unpacking libexpat1-dev:armhf (2.2.6-2+deb10u7) ... May 11 12:38:11 kueche volumio[879]: info: Selecting previously unselected package libpython3.7:armhf. May 11 12:38:11 kueche volumio[879]: info: Preparing to unpack .../26-libpython3.7_3.7.3-2+deb10u7_armhf.deb ... May 11 12:38:11 kueche volumio[879]: info: Unpacking libpython3.7:armhf (3.7.3-2+deb10u7) ... May 11 12:38:12 kueche volumio[879]: info: Selecting previously unselected package libpython3.7-dev:armhf. May 11 12:38:12 kueche volumio[879]: info: Preparing to unpack .../27-libpython3.7-dev_3.7.3-2+deb10u7_armhf.deb ... May 11 12:38:12 kueche volumio[879]: info: Unpacking libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... May 11 12:38:18 kueche volumio[879]: verbose: New Socket.io Connection to 192.168.2.188 from 192.168.2.132 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:138.0) Gecko/20100101 Firefox/138.0 Engine version: 3 Transport: polling Total Clients: 5 May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetVisibleSources May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 11 12:38:18 kueche volumio[879]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 11 12:38:18 kueche volumio[879]: info: Received Get System Info May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 11 12:38:18 kueche volumio[879]: info: Discovery: Getting this device information May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:38:18 kueche volumio[879]: info: Listing playlists May 11 12:38:18 kueche volumio[879]: info: CoreCommandRouter::volumioGetQueue May 11 12:38:18 kueche volumio[879]: info: CoreStateMachine::getQueue May 11 12:38:18 kueche volumio[879]: info: CorePlayQueue::getQueue May 11 12:38:19 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 11 12:38:22 kueche volumio[879]: info: Selecting previously unselected package libpython3-dev:armhf. May 11 12:38:22 kueche volumio[879]: info: Preparing to unpack .../28-libpython3-dev_3.7.3-1_armhf.deb ... May 11 12:38:22 kueche volumio[879]: info: Unpacking libpython3-dev:armhf (3.7.3-1) ... May 11 12:38:22 kueche volumio[879]: info: Selecting previously unselected package python-pip-whl. May 11 12:38:22 kueche volumio[879]: info: Preparing to unpack .../29-python-pip-whl_18.1-5+rpt1_all.deb ... May 11 12:38:22 kueche volumio[879]: info: Unpacking python-pip-whl (18.1-5+rpt1) ... May 11 12:38:22 kueche volumio[879]: info: Selecting previously unselected package python3-multidict. May 11 12:38:22 kueche volumio[879]: info: Preparing to unpack .../30-python3-multidict_4.5.2-1_armhf.deb ... May 11 12:38:22 kueche volumio[879]: info: Unpacking python3-multidict (4.5.2-1) ... May 11 12:38:23 kueche volumio[879]: info: Selecting previously unselected package python3-yarl. May 11 12:38:23 kueche volumio[879]: info: Preparing to unpack .../31-python3-yarl_1.3.0-1_armhf.deb ... May 11 12:38:23 kueche volumio[879]: info: Unpacking python3-yarl (1.3.0-1) ... May 11 12:38:23 kueche volumio[879]: info: Selecting previously unselected package python3-async-timeout. May 11 12:38:23 kueche volumio[879]: info: Preparing to unpack .../32-python3-async-timeout_3.0.1-1_all.deb ... May 11 12:38:23 kueche volumio[879]: info: Unpacking python3-async-timeout (3.0.1-1) ... May 11 12:38:23 kueche volumio[879]: info: Selecting previously unselected package python3-attr. May 11 12:38:23 kueche volumio[879]: info: Preparing to unpack .../33-python3-attr_18.2.0-1_all.deb ... May 11 12:38:23 kueche volumio[879]: info: Unpacking python3-attr (18.2.0-1) ... May 11 12:38:24 kueche volumio[879]: info: Selecting previously unselected package python3-aiohttp. May 11 12:38:24 kueche volumio[879]: info: Preparing to unpack .../34-python3-aiohttp_3.5.1-1+deb10u1_armhf.deb ... May 11 12:38:24 kueche volumio[879]: info: Unpacking python3-aiohttp (3.5.1-1+deb10u1) ... May 11 12:38:24 kueche volumio[879]: info: Selecting previously unselected package python3-asn1crypto. May 11 12:38:24 kueche volumio[879]: info: Preparing to unpack .../35-python3-asn1crypto_0.24.0-1_all.deb ... May 11 12:38:24 kueche volumio[879]: info: Unpacking python3-asn1crypto (0.24.0-1) ... May 11 12:38:25 kueche volumio[879]: info: Selecting previously unselected package python3-cffi-backend. May 11 12:38:25 kueche volumio[879]: info: Preparing to unpack .../36-python3-cffi-backend_1.12.2-1_armhf.deb ... May 11 12:38:25 kueche volumio[879]: info: Unpacking python3-cffi-backend (1.12.2-1) ... May 11 12:38:25 kueche volumio[879]: info: Selecting previously unselected package python3-crypto. May 11 12:38:25 kueche volumio[879]: info: Preparing to unpack .../37-python3-crypto_2.6.1-9+b1_armhf.deb ... May 11 12:38:25 kueche volumio[879]: info: Unpacking python3-crypto (2.6.1-9+b1) ... May 11 12:38:27 kueche volumio[879]: info: Selecting previously unselected package python3-cryptography. May 11 12:38:27 kueche volumio[879]: info: Preparing to unpack .../38-python3-cryptography_2.6.1-3+deb10u4_armhf.deb ... May 11 12:38:27 kueche volumio[879]: info: Unpacking python3-cryptography (2.6.1-3+deb10u4) ... May 11 12:38:29 kueche volumio[879]: info: Selecting previously unselected package python3-dbus. May 11 12:38:29 kueche volumio[879]: info: Preparing to unpack .../39-python3-dbus_1.2.8-3_armhf.deb ... May 11 12:38:29 kueche volumio[879]: info: Unpacking python3-dbus (1.2.8-3) ... May 11 12:38:29 kueche volumio[879]: info: Selecting previously unselected package python3.7-dev. May 11 12:38:29 kueche volumio[879]: info: Preparing to unpack .../40-python3.7-dev_3.7.3-2+deb10u7_armhf.deb ... May 11 12:38:29 kueche volumio[879]: info: Unpacking python3.7-dev (3.7.3-2+deb10u7) ... May 11 12:38:29 kueche volumio[879]: info: Selecting previously unselected package python3-dev. May 11 12:38:29 kueche volumio[879]: info: Preparing to unpack .../41-python3-dev_3.7.3-1_armhf.deb ... May 11 12:38:29 kueche volumio[879]: info: Unpacking python3-dev (3.7.3-1) ... May 11 12:38:29 kueche volumio[879]: info: Selecting previously unselected package python3-entrypoints. May 11 12:38:29 kueche volumio[879]: info: Preparing to unpack .../42-python3-entrypoints_0.3-1_all.deb ... May 11 12:38:29 kueche volumio[879]: info: Unpacking python3-entrypoints (0.3-1) ... May 11 12:38:30 kueche volumio[879]: info: Selecting previously unselected package python3-gi. May 11 12:38:30 kueche volumio[879]: info: Preparing to unpack .../43-python3-gi_3.30.4-1_armhf.deb ... May 11 12:38:30 kueche volumio[879]: info: Unpacking python3-gi (3.30.4-1) ... May 11 12:38:30 kueche volumio[879]: info: Selecting previously unselected package python3-secretstorage. May 11 12:38:30 kueche volumio[879]: info: Preparing to unpack .../44-python3-secretstorage_2.3.1-2_all.deb ... May 11 12:38:30 kueche volumio[879]: info: Unpacking python3-secretstorage (2.3.1-2) ... May 11 12:38:30 kueche volumio[879]: info: Selecting previously unselected package python3-keyring. May 11 12:38:30 kueche volumio[879]: info: Preparing to unpack .../45-python3-keyring_17.1.1-1_all.deb ... May 11 12:38:30 kueche volumio[879]: info: Unpacking python3-keyring (17.1.1-1) ... May 11 12:38:31 kueche volumio[879]: info: Selecting previously unselected package python3-keyrings.alt. May 11 12:38:31 kueche volumio[879]: info: Preparing to unpack .../46-python3-keyrings.alt_3.1.1-1_all.deb ... May 11 12:38:31 kueche volumio[879]: info: Unpacking python3-keyrings.alt (3.1.1-1) ... May 11 12:38:31 kueche volumio[879]: info: Selecting previously unselected package python3-pip. May 11 12:38:31 kueche volumio[879]: info: Preparing to unpack .../47-python3-pip_18.1-5+rpt1_all.deb ... May 11 12:38:31 kueche volumio[879]: info: Unpacking python3-pip (18.1-5+rpt1) ... May 11 12:38:33 kueche volumio[879]: info: Selecting previously unselected package python3-setuptools. May 11 12:38:33 kueche volumio[879]: info: Preparing to unpack .../48-python3-setuptools_40.8.0-1_all.deb ... May 11 12:38:33 kueche volumio[879]: info: Unpacking python3-setuptools (40.8.0-1) ... May 11 12:38:34 kueche volumio[879]: info: Selecting previously unselected package python3-wheel. May 11 12:38:34 kueche volumio[879]: info: Preparing to unpack .../49-python3-wheel_0.32.3-2_all.deb ... May 11 12:38:34 kueche volumio[879]: info: Unpacking python3-wheel (0.32.3-2) ... May 11 12:38:34 kueche volumio[879]: info: Selecting previously unselected package python3-xdg. May 11 12:38:34 kueche volumio[879]: info: Preparing to unpack .../50-python3-xdg_0.25-5_all.deb ... May 11 12:38:34 kueche volumio[879]: info: Unpacking python3-xdg (0.25-5) ... May 11 12:38:35 kueche volumio[879]: info: Setting up python3-entrypoints (0.3-1) ... May 11 12:38:35 kueche volumio[879]: info: Setting up dh-python (3.20190308) ... May 11 12:38:36 kueche volumio[879]: info: Setting up python3-attr (18.2.0-1) ... May 11 12:38:36 kueche volumio[879]: info: Setting up python3-dbus (1.2.8-3) ... May 11 12:38:37 kueche volumio[879]: info: Setting up python3-setuptools (40.8.0-1) ... May 11 12:38:38 kueche volumio[879]: info: Setting up libpython3.7:armhf (3.7.3-2+deb10u7) ... May 11 12:38:38 kueche volumio[879]: info: Setting up libalgorithm-diff-perl (1.19.03-2) ... May 11 12:38:38 kueche volumio[879]: info: Setting up python3-xdg (0.25-5) ... May 11 12:38:38 kueche volumio[879]: info: Setting up bzip2 (1.0.6-9.2~deb10u2) ... May 11 12:38:38 kueche volumio[879]: info: Setting up python3-wheel (0.32.3-2) ... May 11 12:38:39 kueche volumio[879]: info: Setting up python3-multidict (4.5.2-1) ... May 11 12:38:39 kueche volumio[879]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 11 12:38:39 kueche volumio[879]: info: Setting up libfakeroot:armhf (1.23-1) ... May 11 12:38:39 kueche volumio[879]: info: Setting up python3-async-timeout (3.0.1-1) ... May 11 12:38:40 kueche volumio[879]: info: Setting up fakeroot (1.23-1) ... May 11 12:38:40 kueche volumio[879]: info: update-alternatives: using /usr/bin/fakeroot-sysv to provide /usr/bin/fakeroot (fakeroot) in auto mode May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/fakeroot.1.gz because associated file /usr/share/man/es/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/es/man1/faked.1.gz because associated file /usr/share/man/es/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/fakeroot.1.gz because associated file /usr/share/man/fr/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/fr/man1/faked.1.gz because associated file /usr/share/man/fr/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/fakeroot.1.gz because associated file /usr/share/man/sv/man1/fakeroot-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: update-alternatives: warning: skip creation of /usr/share/man/sv/man1/faked.1.gz because associated file /usr/share/man/sv/man1/faked-sysv.1.gz (of link group fakeroot) doesn't exist May 11 12:38:40 kueche volumio[879]: info: Setting up libasan5:armhf (8.3.0-6+rpi1) ... May 11 12:38:40 kueche volumio[879]: info: Setting up libexpat1-dev:armhf (2.2.6-2+deb10u7) ... May 11 12:38:40 kueche volumio[879]: info: Setting up make (4.2.1-1.2) ... May 11 12:38:40 kueche volumio[879]: info: Setting up libmpfr6:armhf (4.0.2-1) ... May 11 12:38:40 kueche volumio[879]: info: Setting up python3-gi (3.30.4-1) ... May 11 12:38:41 kueche volumio[879]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf May 11 12:38:41 kueche volumio[879]: info: Folder /tmp/plugins removed May 11 12:38:41 kueche volumio[879]: info: Setting up libmpc3:armhf (1.1.0-1) ... May 11 12:38:41 kueche volumio[879]: info: Setting up patch (2.7.6-3+deb10u1) ... May 11 12:38:41 kueche volumio[879]: info: Setting up libubsan1:armhf (8.3.0-6+rpi1) ... May 11 12:38:41 kueche volumio[879]: info: Setting up libisl19:armhf (0.20-2) ... May 11 12:38:41 kueche volumio[879]: info: Setting up python3-crypto (2.6.1-9+b1) ... May 11 12:38:41 kueche volumio[879]: info: Check plugin dependencies May 11 12:38:42 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 12:38:42 kueche volumio[879]: info: Checking if plugin already exists May 11 12:38:42 kueche volumio[879]: info: Rename folder May 11 12:38:42 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:38:42 kueche volumio[879]: info: Move to category May 11 12:38:43 kueche volumio[879]: info: Checking if install.sh is present May 11 12:38:43 kueche volumio[879]: info: Executing install.sh May 11 12:38:43 kueche sudo[5340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh May 11 12:38:43 kueche sudo[5340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:43 kueche volumio[879]: info: May 11 12:38:43 kueche volumio[879]: Setting up cpp-8 (8.3.0-6+rpi1) ... May 11 12:38:43 kueche volumio[879]: info: May 11 12:38:43 kueche systemd[1]: Reloading. May 11 12:38:43 kueche volumio[879]: Setting up cpp-8 (8.3.0-6+rpi1) ... May 11 12:38:43 kueche volumio[879]: info: Setting up python3-yarl (1.3.0-1) ... May 11 12:38:43 kueche 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. May 11 12:38:44 kueche volumio[879]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up libalgorithm-diff-xs-perl (0.04-5+b1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up python3-asn1crypto (0.24.0-1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up python3-asn1crypto (0.24.0-1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up libcc1-0:armhf (8.3.0-6+rpi1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up python3-cffi-backend (1.12.2-1) ... May 11 12:38:44 kueche volumio[879]: info: Setting up python3-cffi-backend (1.12.2-1) ... May 11 12:38:45 kueche volumio[879]: info: Setting up libalgorithm-merge-perl (0.08-3) ... May 11 12:38:45 kueche volumio[879]: info: Setting up libalgorithm-merge-perl (0.08-3) ... May 11 12:38:45 kueche volumio[879]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... May 11 12:38:45 kueche volumio[879]: info: Setting up libpython3.7-dev:armhf (3.7.3-2+deb10u7) ... May 11 12:38:45 kueche volumio[879]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... May 11 12:38:45 kueche volumio[879]: info: Setting up python3-aiohttp (3.5.1-1+deb10u1) ... May 11 12:38:45 kueche volumio[879]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... May 11 12:38:45 kueche volumio[879]: info: Setting up python3.7-dev (3.7.3-2+deb10u7) ... May 11 12:38:46 kueche volumio[879]: info: Setting up dpkg-dev (1.19.8) ... May 11 12:38:46 kueche volumio[879]: info: Setting up dpkg-dev (1.19.8) ... May 11 12:38:46 kueche volumio[879]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... May 11 12:38:46 kueche volumio[879]: info: Setting up python3-cryptography (2.6.1-3+deb10u4) ... May 11 12:38:46 kueche systemd[1]: Reloading. May 11 12:38:46 kueche volumio[879]: info: Setting up python3-pip (18.1-5+rpt1) ... May 11 12:38:46 kueche volumio[879]: info: Setting up python3-pip (18.1-5+rpt1) ... May 11 12:38:47 kueche 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. May 11 12:38:47 kueche sudo[5340]: pam_unix(sudo:session): session closed for user root May 11 12:38:47 kueche volumio[879]: info: Install script completed May 11 12:38:47 kueche volumio[879]: info: Adding reference to registry May 11 12:38:47 kueche volumio[879]: info: Done installing plugin. May 11 12:38:47 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 12:38:47 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 12:38:47 kueche volumio[879]: info: Folder /tmp/plugins removed May 11 12:38:47 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:38:47 kueche volumio[879]: info: Folder /data/temp removed May 11 12:38:47 kueche volumio[879]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... May 11 12:38:47 kueche volumio[879]: info: Setting up libgcc-8-dev:armhf (8.3.0-6+rpi1) ... May 11 12:38:47 kueche volumio[879]: info: Setting up cpp (4:8.3.0-1+rpi2) ... May 11 12:38:47 kueche volumio[879]: info: Setting up cpp (4:8.3.0-1+rpi2) ... May 11 12:38:47 kueche volumio[879]: info: Setting up python3-keyrings.alt (3.1.1-1) ... May 11 12:38:47 kueche volumio[879]: info: Setting up python3-keyrings.alt (3.1.1-1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up libstdc++-8-dev:armhf (8.3.0-6+rpi1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up gcc-8 (8.3.0-6+rpi1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... May 11 12:38:48 kueche volumio[879]: info: Setting up libpython3-dev:armhf (3.7.3-1) ... May 11 12:38:48 kueche volumio[879]: info: Enabling plugin spop May 11 12:38:48 kueche volumio[879]: info: Loading plugin "spop"... May 11 12:38:49 kueche volumio[879]: info: PLUGIN START: spop May 11 12:38:49 kueche volumio[879]: info: Creating Spotify config file May 11 12:38:50 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:38:50 kueche volumio[879]: info: Done. May 11 12:38:50 kueche volumio[879]: info: No need to fix Spotify hosts May 11 12:38:50 kueche volumio[879]: info: Spotify config file written May 11 12:38:50 kueche volumio[879]: info: Setting up gcc (4:8.3.0-1+rpi2) ... May 11 12:38:50 kueche sudo[5476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 11 12:38:50 kueche sudo[5476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:50 kueche volumio[879]: info: Setting up gcc (4:8.3.0-1+rpi2) ... May 11 12:38:50 kueche systemd[1]: Started go-librespot Daemon. May 11 12:38:50 kueche go-librespot[5480]: go-librespot daemon starting... May 11 12:38:50 kueche sudo[5476]: pam_unix(sudo:session): session closed for user root May 11 12:38:50 kueche volumio[879]: info: Setting up python3-secretstorage (2.3.1-2) ... May 11 12:38:50 kueche volumio[879]: info: Setting up python3-secretstorage (2.3.1-2) ... May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=info msg="running go-librespot 0.2.0" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=debug msg="no app state found" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=debug msg="stored credentials not found" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=info msg="generated new device id: 402bb96873df6eef472a5facd8687341219419ef" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=info msg="api server listening on 127.0.0.1:9879" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" May 11 12:38:50 kueche go-librespot[5480]: time="2025-05-11T12:38:50+02:00" level=info msg="zeroconf server listening on port 44075" May 11 12:38:50 kueche volumio[879]: info: Setting up python3-dev (3.7.3-1) ... May 11 12:38:50 kueche volumio[879]: info: Setting up python3-dev (3.7.3-1) ... May 11 12:38:50 kueche volumio[879]: info: Setting up g++-8 (8.3.0-6+rpi1) ... May 11 12:38:50 kueche volumio[879]: info: Setting up g++-8 (8.3.0-6+rpi1) ... May 11 12:38:50 kueche volumio[879]: info: Setting up python3-keyring (17.1.1-1) ... May 11 12:38:50 kueche volumio[879]: info: Setting up python3-keyring (17.1.1-1) ... May 11 12:38:51 kueche volumio[879]: info: Setting up g++ (4:8.3.0-1+rpi2) ... May 11 12:38:51 kueche volumio[879]: info: Setting up g++ (4:8.3.0-1+rpi2) ... May 11 12:38:51 kueche volumio[879]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode May 11 12:38:51 kueche volumio[879]: info: update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode May 11 12:38:51 kueche volumio[879]: info: Setting up build-essential (12.6) ... May 11 12:38:51 kueche volumio[879]: info: Setting up build-essential (12.6) ... May 11 12:38:51 kueche volumio[879]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... May 11 12:38:51 kueche volumio[879]: info: Processing triggers for libc-bin (2.28-10+rpt2+rpi1+deb10u2) ... May 11 12:38:52 kueche volumio[879]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 11 12:38:53 kueche sudo[4508]: pam_unix(sudo:session): session closed for user root May 11 12:38:53 kueche sudo[5535]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 11 12:38:53 kueche sudo[5535]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:53 kueche sudo[5535]: pam_unix(sudo:session): session closed for user root May 11 12:38:53 kueche volumio[879]: info: lib/systemd/system/fusiondsp.service May 11 12:38:53 kueche volumio[879]: info: lib/systemd/system/fusiondsp.service May 11 12:38:53 kueche volumio[879]: info: go-librespot daemon successfully initialized May 11 12:38:54 kueche volumio[879]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 11 12:38:54 kueche sudo[5539]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 11 12:38:54 kueche sudo[5539]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:54 kueche volumio[879]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 11 12:38:54 kueche volumio[879]: info: May 11 12:38:54 kueche volumio[879]: info: cgui-1.0.0.zip opened May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/ May 11 12:38:54 kueche sudo[5539]: pam_unix(sudo:session): session closed for user root May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/LICENSE.txt May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/README.md May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/build/ May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/robots.txt May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/logo512.png May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/asset-manifest.json May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/index.html May 11 12:38:54 kueche sudo[5541]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 11 12:38:54 kueche sudo[5541]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/.put_statics_here May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/favicon.ico May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/logo192.png May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 11 12:38:54 kueche sudo[5541]: pam_unix(sudo:session): session closed for user root May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/manifest.json May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/css-variables.css May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/service-worker.js May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/build/static/ May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/build/static/css/ May 11 12:38:54 kueche sudo[5543]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 11 12:38:54 kueche sudo[5543]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 11 12:38:54 kueche sudo[5543]: pam_unix(sudo:session): session closed for user root May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/build/static/media/ May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 11 12:38:54 kueche volumio[879]: info: creating directory: cgui/build/static/js/ May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 11 12:38:54 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/config/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/config/camillagui.yml May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/config/gui-config.yml May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/backend/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/filters_test.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/version.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/filters.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/settings.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/views.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/filterdefaults.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__init__.py May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/backend/__pycache__/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/routes.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/backend/filemanagement.py May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/main.py May 11 12:38:55 kueche volumio[879]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 11 12:38:55 kueche volumio[879]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 11 12:38:55 kueche volumio[879]: info: May 11 12:38:55 kueche volumio[879]: info: cgui-1.0.0.zip opened May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/README.md May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/build/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/robots.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/logo512.png May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/asset-manifest.json May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/index.html May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/.put_statics_here May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/favicon.ico May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/logo192.png May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/manifest.json May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/css-variables.css May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/service-worker.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/build/static/ May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/build/static/css/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/build/static/media/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 11 12:38:55 kueche volumio[879]: info: creating directory: cgui/build/static/js/ May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 11 12:38:55 kueche volumio[879]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 11 12:38:56 kueche volumio[879]: info: creating directory: cgui/config/ May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/config/camillagui.yml May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/config/gui-config.yml May 11 12:38:56 kueche volumio[879]: info: creating directory: cgui/backend/ May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/filters_test.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/version.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/filters.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/settings.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/views.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/filterdefaults.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__init__.py May 11 12:38:56 kueche volumio[879]: info: creating directory: cgui/backend/__pycache__/ May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/routes.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/backend/filemanagement.py May 11 12:38:56 kueche volumio[879]: info: extracting: cgui/main.py May 11 12:38:56 kueche volumio[879]: info: Initializing connection to go-librespot Websocket May 11 12:38:57 kueche go-librespot[5480]: time="2025-05-11T12:38:57+02:00" level=debug msg="new websocket client" May 11 12:38:57 kueche volumio[879]: info: Connection to go-librespot Websocket established May 11 12:38:58 kueche volumio[879]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 11 12:38:59 kueche volumio[879]: info: Check plugin dependencies May 11 12:38:59 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 12:38:59 kueche volumio[879]: info: Rename folder May 11 12:38:59 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:38:59 kueche volumio[879]: info: Move to category May 11 12:39:01 kueche volumio[879]: info: Checking if install.sh is present May 11 12:39:01 kueche volumio[879]: info: Getting Spotify volume May 11 12:39:01 kueche volumio[879]: info: Executing install.sh May 11 12:39:01 kueche volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 11 12:39:01 kueche sudo[5581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 11 12:39:01 kueche sudo[5581]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:01 kueche volumio[879]: info: creating filters folder and copying demo filters May 11 12:39:01 kueche volumio[879]: info: creating filters folder and copying demo filters May 11 12:39:01 kueche volumio[879]: info: creating filters folder and copying demo filters May 11 12:39:01 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:01 kueche volumio[879]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 May 11 12:39:01 kueche volumio[879]: SPOTIFY: SPOTIFY VOLUME undefined May 11 12:39:01 kueche volumio[879]: SPOTIFY: VOLUMIO VOLUME 12 May 11 12:39:01 kueche volumio[879]: info: Aligning Spotify Volume to Volumio Volume May 11 12:39:01 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:01 kueche volumio[879]: info: Setting Spotify Volume from Volumio: 12 May 11 12:39:01 kueche volumio[879]: info: copying demo flters May 11 12:39:01 kueche volumio[879]: info: copying demo flters May 11 12:39:01 kueche volumio[879]: info: copying demo flters May 11 12:39:02 kueche volumio[879]: info: ./presets/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/PEQ/ May 11 12:39:02 kueche sudo[5606]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/ May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/kef.json May 11 12:39:02 kueche sudo[5606]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/test.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Rock-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/PEQ/ May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/ May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/kef.json May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/test.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Rock-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/PEQ/ May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/ May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/kef.json May 11 12:39:02 kueche volumio[879]: info: ./presets/convfir/test.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/ May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Bass-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Classic-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Voice-FusionDsp.json May 11 12:39:02 kueche volumio[879]: info: ./presets/EQ15/.Rock-FusionDsp.json May 11 12:39:03 kueche volumio[879]: SPOTIFY: SETTING SPOTIFY VOLUME 12 May 11 12:39:03 kueche volumio[879]: info: Sending Spotify command with payload to local API: /player/volume May 11 12:39:03 kueche volumio[879]: info: Installing/fusiondsp dependencies May 11 12:39:03 kueche volumio[879]: info: Installing/fusiondsp dependencies May 11 12:39:03 kueche volumio[879]: info: Installing/fusiondsp dependencies May 11 12:39:04 kueche volumio[879]: info: May 11 12:39:04 kueche volumio[879]: copying hw detection script May 11 12:39:04 kueche volumio[879]: info: May 11 12:39:04 kueche volumio[879]: copying hw detection script May 11 12:39:04 kueche volumio[879]: info: May 11 12:39:04 kueche volumio[879]: copying hw detection script May 11 12:39:04 kueche volumio[879]: info: Detected cpu architecture as armv7l May 11 12:39:04 kueche volumio[879]: info: Detected cpu architecture as armv7l May 11 12:39:05 kueche sudo[5625]: 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 May 11 12:39:05 kueche sudo[5625]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:05 kueche sudo[5625]: pam_unix(sudo:session): session closed for user root May 11 12:39:05 kueche sudo[5627]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params May 11 12:39:05 kueche sudo[5627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:05 kueche sudo[5627]: pam_unix(sudo:session): session closed for user root May 11 12:39:05 kueche sudo[5629]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 11 12:39:05 kueche sudo[5629]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:05 kueche volumio[879]: info: Reading package lists... May 11 12:39:05 kueche volumio[879]: info: Reading package lists... May 11 12:39:05 kueche volumio[879]: info: Reading package lists... May 11 12:39:06 kueche volumio[879]: info: Building dependency tree... May 11 12:39:06 kueche volumio[879]: info: Building dependency tree... May 11 12:39:06 kueche volumio[879]: info: Building dependency tree... May 11 12:39:06 kueche volumio[879]: info: Reading state information... May 11 12:39:06 kueche volumio[879]: info: Reading state information... May 11 12:39:06 kueche volumio[879]: info: Reading state information... May 11 12:39:07 kueche volumio[879]: info: The following NEW packages will be installed: May 11 12:39:07 kueche volumio[879]: info: The following NEW packages will be installed: May 11 12:39:07 kueche volumio[879]: info: The following NEW packages will be installed: May 11 12:39:07 kueche volumio[879]: info: drc May 11 12:39:07 kueche volumio[879]: info: drc May 11 12:39:07 kueche volumio[879]: info: drc May 11 12:39:07 kueche volumio[879]: info: 0 upgraded, 1 newly installed, 0 to remove and 2 not upgraded. May 11 12:39:07 kueche volumio[879]: info: Need to get 112 kB of archives. May 11 12:39:07 kueche volumio[879]: info: After this operation, 512 kB of additional disk space will be used. May 11 12:39:07 kueche volumio[879]: info: Get:1 http://mirror.netcologne.de/raspbian/raspbian buster/main armhf drc armhf 3.2.2~dfsg0-2 [112 kB] May 11 12:39:07 kueche volumio[879]: info: 0 upgraded, 1 newly installed, 0 to remove and 2 not upgraded. May 11 12:39:07 kueche volumio[879]: info: Need to get 112 kB of archives. May 11 12:39:07 kueche volumio[879]: info: After this operation, 512 kB of additional disk space will be used. May 11 12:39:07 kueche volumio[879]: info: Get:1 http://mirror.netcologne.de/raspbian/raspbian buster/main armhf drc armhf 3.2.2~dfsg0-2 [112 kB] May 11 12:39:07 kueche volumio[879]: info: 0 upgraded, 1 newly installed, 0 to remove and 2 not upgraded. May 11 12:39:07 kueche volumio[879]: info: Need to get 112 kB of archives. May 11 12:39:07 kueche volumio[879]: info: After this operation, 512 kB of additional disk space will be used. May 11 12:39:07 kueche volumio[879]: info: Get:1 http://mirror.netcologne.de/raspbian/raspbian buster/main armhf drc armhf 3.2.2~dfsg0-2 [112 kB] May 11 12:39:08 kueche volumio[879]: info: Fetched 112 kB in 0s (384 kB/s) May 11 12:39:08 kueche volumio[879]: info: Fetched 112 kB in 0s (384 kB/s) May 11 12:39:08 kueche volumio[879]: info: Fetched 112 kB in 0s (384 kB/s) May 11 12:39:08 kueche volumio[879]: info: Selecting previously unselected package drc. May 11 12:39:08 kueche volumio[879]: info: Selecting previously unselected package drc. May 11 12:39:08 kueche volumio[879]: info: Selecting previously unselected package drc. May 11 12:39:08 kueche volumio[879]: [620B blob data] May 11 12:39:08 kueche volumio[879]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... May 11 12:39:08 kueche volumio[879]: info: Unpacking drc (3.2.2~dfsg0-2) ... May 11 12:39:08 kueche volumio[879]: [620B blob data] May 11 12:39:08 kueche volumio[879]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... May 11 12:39:08 kueche volumio[879]: info: Unpacking drc (3.2.2~dfsg0-2) ... May 11 12:39:09 kueche volumio[879]: [620B blob data] May 11 12:39:09 kueche volumio[879]: info: Preparing to unpack .../drc_3.2.2~dfsg0-2_armhf.deb ... May 11 12:39:09 kueche volumio[879]: info: Unpacking drc (3.2.2~dfsg0-2) ... May 11 12:39:10 kueche volumio[879]: info: Setting up drc (3.2.2~dfsg0-2) ... May 11 12:39:10 kueche volumio[879]: info: Setting up drc (3.2.2~dfsg0-2) ... May 11 12:39:10 kueche volumio[879]: info: Setting up drc (3.2.2~dfsg0-2) ... May 11 12:39:10 kueche sudo[5629]: pam_unix(sudo:session): session closed for user root May 11 12:39:10 kueche sudo[4248]: pam_unix(sudo:session): session closed for user root May 11 12:39:10 kueche volumio[879]: Plugin install end detected on script May 11 12:39:10 kueche volumio[879]: Plugin install end detected on script May 11 12:39:10 kueche volumio[879]: Plugin install end detected on script May 11 12:39:10 kueche volumio[879]: info: Install script completed May 11 12:39:10 kueche volumio[879]: info: Adding reference to registry May 11 12:39:10 kueche volumio[879]: info: Done installing plugin. May 11 12:39:10 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 12:39:10 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 12:39:10 kueche volumio[879]: info: Folder /tmp/plugins removed May 11 12:39:10 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:39:10 kueche volumio[879]: info: Folder /data/temp removed May 11 12:39:12 kueche sudo[5606]: pam_unix(sudo:session): session closed for user root May 11 12:39:12 kueche sudo[5868]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 11 12:39:12 kueche sudo[5868]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:13 kueche sudo[5868]: pam_unix(sudo:session): session closed for user root May 11 12:39:13 kueche sudo[5872]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 11 12:39:13 kueche sudo[5872]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:13 kueche sudo[5872]: pam_unix(sudo:session): session closed for user root May 11 12:39:13 kueche volumio[879]: info: Enabling plugin fusiondsp May 11 12:39:13 kueche volumio[879]: info: Loading plugin "fusiondsp"... May 11 12:39:14 kueche volumio[879]: info: Preparing to generate the ALSA configuration file May 11 12:39:14 kueche volumio[879]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 11 12:39:14 kueche volumio[879]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 11 12:39:14 kueche volumio[879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 11 12:39:14 kueche volumio[879]: info: Reading ALSA contributions from plugins. May 11 12:39:14 kueche volumio[879]: info: Asound.conf file written May 11 12:39:14 kueche sudo[5876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 11 12:39:14 kueche sudo[5876]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche sudo[5876]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:14 kueche volumio[879]: No state is present for card Headphones May 11 12:39:14 kueche volumio[879]: /usr/share/alsa/init/bcm2835:20: control element not found May 11 12:39:14 kueche volumio[879]: No state is present for card Headphones May 11 12:39:14 kueche volumio[879]: No state is present for card sndrpihifiberry May 11 12:39:14 kueche volumio[879]: Found hardware: "HifiberryDacp" "" "" "" "" May 11 12:39:14 kueche volumio[879]: Hardware is initialized using a generic method May 11 12:39:14 kueche volumio[879]: No state is present for card sndrpihifiberry May 11 12:39:14 kueche volumio[879]: info: Output device has changed, restarting MPD May 11 12:39:14 kueche sudo[5881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 12:39:14 kueche sudo[5881]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche sudo[5881]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche volumio[879]: info: Output device has changed, restarting Shairport Sync May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 12:39:14 kueche sudo[5884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 12:39:14 kueche sudo[5884]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche systemd[1]: Stopping Music Player Daemon... May 11 12:39:14 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:14 kueche volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 12:39:14 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:14 kueche volumio[879]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche systemd[1]: mpd.service: Succeeded. May 11 12:39:14 kueche systemd[1]: Stopped Music Player Daemon. May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: setDeactiveState invoked May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:14 kueche vtcs[3974]: [2025-05-11 12:39:14.339] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE May 11 12:39:14 kueche sudo[5893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 11 12:39:14 kueche volumio[879]: info: PLUGIN START: fusiondsp May 11 12:39:14 kueche volumio[879]: info: Loading i18n strings for locale de May 11 12:39:14 kueche systemd[1]: Starting Music Player Daemon... May 11 12:39:14 kueche vtcs[3974]: [2025-05-11 12:39:14.340] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=5 errorno=104 error=Connection reset by peer May 11 12:39:14 kueche vtcs[3974]: [2025-05-11 12:39:14.340] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected May 11 12:39:14 kueche sudo[5893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche volumio[879]: info: FusionDsp - mixtype--------------------- Software May 11 12:39:14 kueche volumio[879]: info: Preparing to generate the ALSA configuration file May 11 12:39:14 kueche systemd[1]: Stopping Volumio Tidal Connect Service... May 11 12:39:14 kueche systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 11 12:39:14 kueche volumio[879]: info: Done. May 11 12:39:14 kueche systemd[1]: vtcs.service: Succeeded. May 11 12:39:14 kueche systemd[1]: Stopped Volumio Tidal Connect Service. May 11 12:39:14 kueche sudo[5893]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche sudo[5897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 11 12:39:14 kueche sudo[5897]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche sudo[5890]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 12:39:14 kueche sudo[5890]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche sudo[5890]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 2 May 11 12:39:14 kueche sudo[5897]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche volumio[879]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 11 12:39:14 kueche volumio[879]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 11 12:39:14 kueche volumio[879]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 11 12:39:14 kueche volumio[879]: info: Reading ALSA contributions from plugins. May 11 12:39:14 kueche sudo[5906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 11 12:39:14 kueche sudo[5906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:14 kueche volumio[879]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 11 12:39:14 kueche volumio[879]: info: MPD Permissions set May 11 12:39:14 kueche qobuz-connect[3910]: 20250511 12:39:14.545 [3910.3910] INFO SampleApp: Stopping Local configuration server May 11 12:39:14 kueche systemd[1]: Stopping Volumio Qobuz Connect Service... May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche qobuz-connect[3910]: 20250511 12:39:14.555 [3910.3910] INFO SampleApp: shat down connection on UNIX socket May 11 12:39:14 kueche systemd[1]: qobuz-connect.service: Succeeded. May 11 12:39:14 kueche systemd[1]: Stopped Volumio Qobuz Connect Service. May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:14 kueche systemd[1]: Started Volumio Qobuz Connect Service. May 11 12:39:14 kueche sudo[5906]: pam_unix(sudo:session): session closed for user root May 11 12:39:14 kueche volumio[879]: info: FusionDsp - May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:14 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: setDeactiveState invoked May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:14 kueche volumio[879]: info: FusionDsp - May 11 12:39:14 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:14 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:14 kueche volumio[879]: info: Starting Shairport Sync May 11 12:39:14 kueche sudo[5916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 12:39:14 kueche sudo[5916]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:14 kueche volumio[879]: info: Executing endpoint qc_getconfig May 11 12:39:14 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 11 12:39:14 kueche qobuz-connect[5909]: 20250511 12:39:14.990 [5909.5909] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 11 12:39:14 kueche volumio[879]: info: QobuzConnect: QOBUZ Connect daemon connected May 11 12:39:14 kueche volumio[879]: info: Asound.conf file unchanged, so no further update is needed May 11 12:39:14 kueche volumio[879]: info: Output device has changed, restarting MPD May 11 12:39:14 kueche qobuz-connect[5909]: 20250511 12:39:14.995 [5909.5909] INFO VolumeManager: [0x1dd3910]: Setting new playback volume: 75 May 11 12:39:14 kueche qobuz-connect[5909]: 20250511 12:39:14.995 [5909.5909] INFO VolumeManager: [0x1dd3910]: Setting new mute state: 0 May 11 12:39:14 kueche qobuz-connect[5909]: 20250511 12:39:14.995 [5909.5909] INFO QobuzConnect: [0x1dd42e0]: Client initialized! May 11 12:39:14 kueche qobuz-connect[5909]: 20250511 12:39:14.995 [5909.5909] INFO SampleApp: Starting Avahi advertising, name: Kueche, service name: _qobuz-connect._tcp May 11 12:39:15 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 12:39:15 kueche systemd[1]: shairport-sync.service: Succeeded. May 11 12:39:15 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.024 [5909.5909] INFO LocalConfigManager: [0x1dd31f0]: Starting Local Configuration server May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.025 [5909.5909] INFO SampleApp: Starting Local configuration server May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.025 [5909.5909] INFO SampleApp: Connected to UNIX socket client 0x1dc8f60 May 11 12:39:15 kueche sudo[5921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 11 12:39:15 kueche sudo[5921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche volumio[879]: info: Output device has changed, restarting Shairport Sync May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 12:39:15 kueche sudo[5921]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 12:39:15 kueche sudo[5916]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche sudo[5924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 11 12:39:15 kueche sudo[5924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 11 12:39:15 kueche systemd[1]: mpd.service: Succeeded. May 11 12:39:15 kueche systemd[1]: Stopped Music Player Daemon. May 11 12:39:15 kueche systemd[1]: Starting Music Player Daemon... May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.140 [5909.5909] INFO SampleApp: Playback volume changed: 75 May 11 12:39:15 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:15 kueche volumio[879]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 11 12:39:15 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:15 kueche volumio[879]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: setDeactiveState invoked May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:15 kueche sudo[5931]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 11 12:39:15 kueche sudo[5931]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche sudo[5931]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche sudo[5936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 11 12:39:15 kueche sudo[5936]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche sudo[5941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 11 12:39:15 kueche sudo[5941]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: Setting Qobuz Connect card as outputdevice to 2 May 11 12:39:15 kueche sudo[5941]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche sudo[5936]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:15 kueche sudo[5950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect May 11 12:39:15 kueche sudo[5950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:15 kueche volumio[879]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined May 11 12:39:15 kueche volumio[879]: info: MPD Permissions set May 11 12:39:15 kueche volumio[879]: info: Shairport-Sync Started May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche systemd[1]: Stopping Volumio Qobuz Connect Service... May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.354 [5909.5909] INFO SampleApp: Stopping Local configuration server May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:15 kueche qobuz-connect[5909]: 20250511 12:39:15.366 [5909.5909] INFO SampleApp: shat down connection on UNIX socket May 11 12:39:15 kueche systemd[1]: qobuz-connect.service: Succeeded. May 11 12:39:15 kueche systemd[1]: Stopped Volumio Qobuz Connect Service. May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: setDeactiveState invoked May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:15 kueche volumio[879]: info: Starting Shairport Sync May 11 12:39:15 kueche systemd[1]: Started Volumio Qobuz Connect Service. May 11 12:39:15 kueche sudo[5950]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche sudo[5960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 11 12:39:15 kueche sudo[5960]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:15 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 11 12:39:15 kueche systemd[1]: shairport-sync.service: Succeeded. May 11 12:39:15 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 11 12:39:15 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 11 12:39:15 kueche sudo[5960]: pam_unix(sudo:session): session closed for user root May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: running May 11 12:39:15 kueche volumio[879]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 11 12:39:15 kueche volumio[879]: info: Shairport-Sync Started May 11 12:39:15 kueche volumio[879]: info: Executing endpoint qc_getconfig May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.749 [5956.5956] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] May 11 12:39:15 kueche volumio[879]: info: QobuzConnect: QOBUZ Connect daemon connected May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.759 [5956.5956] INFO VolumeManager: [0x1305910]: Setting new playback volume: 75 May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.759 [5956.5956] INFO VolumeManager: [0x1305910]: Setting new mute state: 0 May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.759 [5956.5956] INFO QobuzConnect: [0x13062e0]: Client initialized! May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.759 [5956.5956] INFO SampleApp: Starting Avahi advertising, name: Kueche, service name: _qobuz-connect._tcp May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.779 [5956.5956] INFO LocalConfigManager: [0x13051f0]: Starting Local Configuration server May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.779 [5956.5956] INFO SampleApp: Starting Local configuration server May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.779 [5956.5956] INFO SampleApp: Connected to UNIX socket client 0x12faf60 May 11 12:39:15 kueche qobuz-connect[5956]: 20250511 12:39:15.899 [5956.5956] INFO SampleApp: Playback volume changed: 75 May 11 12:39:15 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:16 kueche mpd[5938]: May 11 12:39 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 11 12:39:16 kueche systemd[1]: Started Music Player Daemon. May 11 12:39:16 kueche sudo[5884]: pam_unix(sudo:session): session closed for user root May 11 12:39:16 kueche sudo[5924]: pam_unix(sudo:session): session closed for user root May 11 12:39:16 kueche volumio[879]: error: MPD error: The expression evaluated to a falsy value: May 11 12:39:16 kueche volumio[879]: assert.ok(self.idling) May 11 12:39:16 kueche volumio[879]: error: The expression evaluated to a falsy value: May 11 12:39:16 kueche volumio[879]: assert.ok(self.idling) May 11 12:39:16 kueche volumio[879]: error: updateQueue error: null May 11 12:39:16 kueche sudo[5969]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 11 12:39:16 kueche sudo[5969]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:16 kueche sudo[5969]: pam_unix(sudo:session): session closed for user root May 11 12:39:16 kueche sudo[5971]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 11 12:39:16 kueche sudo[5971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:16 kueche sudo[5971]: pam_unix(sudo:session): session closed for user root May 11 12:39:16 kueche sudo[5973]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 11 12:39:16 kueche sudo[5973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:16 kueche sudo[5973]: pam_unix(sudo:session): session closed for user root May 11 12:39:16 kueche volumio[879]: info: camilladsp service started and running in background, instance 1 May 11 12:39:16 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 11 12:39:16 kueche volumio[879]: xcb_connection_has_error() returned true May 11 12:39:16 kueche volumio[879]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 11 12:39:16 kueche volumio[879]: info: FusionDsp loaded May 11 12:39:16 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 11 12:39:16 kueche sudo[5988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 11 12:39:16 kueche sudo[5988]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:16 kueche volumio[879]: info: FusionDsp - Reporting Fusion DSP Enabled May 11 12:39:16 kueche volumio[879]: info: Adding Signal Path Element [object Object] May 11 12:39:16 kueche volumio[879]: info: Adding fusiondspeq DSP Signal Path Element May 11 12:39:16 kueche volumio[879]: info: FusionDsp - ---- installed callbackRead May 11 12:39:16 kueche volumio[879]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 11 12:39:16 kueche volumio[879]: error: FusionDsp - WebSocket error: [object Object] May 11 12:39:16 kueche volumio[879]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 11 12:39:16 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:16 kueche sudo[5988]: pam_unix(sudo:session): session closed for user root May 11 12:39:17 kueche volumio[5990]: Traceback (most recent call last): May 11 12:39:17 kueche volumio[5990]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 11 12:39:17 kueche volumio[5990]: from camilladsp import CamillaConnection May 11 12:39:17 kueche volumio[5990]: ModuleNotFoundError: No module named 'camilladsp' May 11 12:39:17 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:17 kueche volumio[879]: info: TidalConnect service stoped! May 11 12:39:17 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:17 kueche volumio[879]: info: TidalConnect service stoped! May 11 12:39:17 kueche volumio[879]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 11 12:39:17 kueche volumio[879]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 11 12:39:17 kueche sudo[6000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 11 12:39:17 kueche sudo[6000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:17 kueche volumio[879]: 0+0 records in May 11 12:39:17 kueche volumio[879]: 0+0 records out May 11 12:39:17 kueche volumio[879]: 0 bytes copied, 0.000296846 s, 0.0 kB/s May 11 12:39:17 kueche systemd[1]: Started Volumio Tidal Connect Service. May 11 12:39:17 kueche sudo[6000]: pam_unix(sudo:session): session closed for user root May 11 12:39:17 kueche volumio[879]: info: Executing endpoint tc_getconfig May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 11 12:39:17 kueche vtcs[6004]: STARTING TidalConnect services, version: 1.4.0.34 May 11 12:39:17 kueche vtcs[6004]: STARTED TidalConnect services. May 11 12:39:17 kueche volumio[879]: info: Executing endpoint tc_connect May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 11 12:39:17 kueche volumio[879]: info: Connecting to TidalConnect May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::servicePushState May 11 12:39:17 kueche volumio[879]: info: CoreStateMachine::pushState May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::volumioPushState May 11 12:39:17 kueche volumio[879]: info: MRS: Pushing multiroomSync output update for this device May 11 12:39:17 kueche volumio[879]: info: MRS: Pushing multiroomSync output May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:17 kueche volumio[879]: info: CorePlayQueue::getTrack 0 May 11 12:39:17 kueche volumio[879]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::servicePushState May 11 12:39:17 kueche volumio[879]: info: CoreStateMachine::pushState May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::volumioPushState May 11 12:39:17 kueche volumio[879]: info: MRS: Pushing multiroomSync output update for this device May 11 12:39:17 kueche volumio[879]: info: MRS: Pushing multiroomSync output May 11 12:39:17 kueche volumio[879]: info: CoreCommandRouter::volumioGetState May 11 12:39:17 kueche volumio[879]: info: CorePlayQueue::getTrack 0 May 11 12:39:17 kueche volumio[879]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect May 11 12:39:18 kueche volumio[879]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.F7UFPF1AcUfYHg8S6sQS5nTtxiz1.1fc43e78d98ad350b4e1f6420f816cf0.state.status' May 11 12:39:18 kueche volumio[879]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 May 11 12:39:18 kueche volumio[879]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 May 11 12:39:18 kueche volumio[879]: info: TidalConnect service stoped! May 11 12:39:18 kueche volumio[879]: info: TidalConnect service stoped! May 11 12:39:18 kueche volumio[879]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 11 12:39:18 kueche volumio[879]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 11 12:39:18 kueche sudo[6110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 11 12:39:18 kueche sudo[6110]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:18 kueche sudo[6110]: pam_unix(sudo:session): session closed for user root May 11 12:39:19 kueche volumio[879]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf May 11 12:39:19 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:19 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 1. May 11 12:39:19 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:19 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:20 kueche volumio[6273]: Traceback (most recent call last): May 11 12:39:20 kueche volumio[6273]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 11 12:39:20 kueche volumio[6273]: from camilladsp import CamillaConnection May 11 12:39:20 kueche volumio[6273]: ModuleNotFoundError: No module named 'camilladsp' May 11 12:39:20 kueche volumio[879]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/backup_restore/volumio/buster/armhf May 11 12:39:20 kueche volumio[879]: info: Folder /tmp/plugins removed May 11 12:39:20 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:20 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:20 kueche volumio[879]: info: TidalConnect service started! May 11 12:39:21 kueche volumio[879]: info: Check plugin dependencies May 11 12:39:21 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 11 12:39:21 kueche volumio[879]: info: Checking if plugin already exists May 11 12:39:21 kueche volumio[879]: info: Rename folder May 11 12:39:21 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:39:21 kueche volumio[879]: info: Move to category May 11 12:39:21 kueche volumio[879]: info: Checking if install.sh is present May 11 12:39:21 kueche volumio[879]: info: Executing install.sh May 11 12:39:21 kueche volumio[879]: info: TidalConnect service started! May 11 12:39:21 kueche sudo[6392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/backup_restore/install.sh May 11 12:39:21 kueche sudo[6392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:21 kueche sudo[6392]: pam_unix(sudo:session): session closed for user root May 11 12:39:21 kueche volumio[879]: info: Installing backup restore Dependencies May 11 12:39:21 kueche volumio[879]: Plugin install end detected on script May 11 12:39:21 kueche volumio[879]: info: Install script completed May 11 12:39:21 kueche volumio[879]: info: Adding reference to registry May 11 12:39:21 kueche volumio[879]: info: Done installing plugin. May 11 12:39:21 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 11 12:39:21 kueche volumio[879]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 11 12:39:21 kueche volumio[879]: info: Folder /tmp/plugins removed May 11 12:39:21 kueche volumio[879]: info: Folder /tmp/downloaded_plugin.zip removed May 11 12:39:21 kueche volumio[879]: info: Folder /data/temp removed May 11 12:39:21 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:21 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:21 kueche volumio[879]: info: FusionDsp - May 11 12:39:21 kueche volumio[879]: info: FusionDsp - May 11 12:39:22 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:22 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:22 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:22 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 2. May 11 12:39:22 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:22 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:23 kueche volumio[6474]: Traceback (most recent call last): May 11 12:39:23 kueche volumio[6474]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 11 12:39:23 kueche volumio[6474]: from camilladsp import CamillaConnection May 11 12:39:23 kueche volumio[6474]: ModuleNotFoundError: No module named 'camilladsp' May 11 12:39:23 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:23 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:23 kueche volumio[879]: info: Enabling plugin backup_restore May 11 12:39:23 kueche volumio[879]: info: Loading plugin "backup_restore"... May 11 12:39:23 kueche volumio[879]: info: PLUGIN START: backup_restore May 11 12:39:23 kueche volumio[879]: info: Done. May 11 12:39:23 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:23 kueche volumio[879]: info: CoreCommandRouter::getUIConfigOnPlugin May 11 12:39:23 kueche volumio[879]: info: FusionDsp - May 11 12:39:23 kueche volumio[879]: info: FusionDsp - May 11 12:39:24 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:24 kueche volumio[879]: info: FusionDsp - undefined May 11 12:39:25 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:25 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 3. May 11 12:39:25 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:25 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:26 kueche volumio[6806]: Traceback (most recent call last): May 11 12:39:26 kueche volumio[6806]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 4, in May 11 12:39:26 kueche volumio[6806]: from camilladsp import CamillaConnection May 11 12:39:26 kueche volumio[6806]: ModuleNotFoundError: No module named 'camilladsp' May 11 12:39:26 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:26 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:28 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:28 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 4. May 11 12:39:28 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:28 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:29 kueche volumio[7189]: Traceback (most recent call last): May 11 12:39:29 kueche volumio[7189]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in May 11 12:39:29 kueche volumio[7189]: from camilladsp_plot.validate_config import CamillaValidator May 11 12:39:29 kueche volumio[7189]: ModuleNotFoundError: No module named 'camilladsp_plot' May 11 12:39:29 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:29 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:31 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:31 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 5. May 11 12:39:31 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:31 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:32 kueche volumio[7598]: Traceback (most recent call last): May 11 12:39:32 kueche volumio[7598]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in May 11 12:39:32 kueche volumio[7598]: from camilladsp_plot.validate_config import CamillaValidator May 11 12:39:32 kueche volumio[7598]: ModuleNotFoundError: No module named 'camilladsp_plot' May 11 12:39:32 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:32 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:34 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:34 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 6. May 11 12:39:34 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:34 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:35 kueche volumio[8043]: Traceback (most recent call last): May 11 12:39:35 kueche volumio[8043]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in May 11 12:39:35 kueche volumio[8043]: from camilladsp_plot.validate_config import CamillaValidator May 11 12:39:35 kueche volumio[8043]: ModuleNotFoundError: No module named 'camilladsp_plot' May 11 12:39:35 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:35 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:37 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:37 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 7. May 11 12:39:37 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:37 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:38 kueche volumio[8436]: Traceback (most recent call last): May 11 12:39:38 kueche volumio[8436]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in May 11 12:39:38 kueche volumio[8436]: from camilladsp_plot.validate_config import CamillaValidator May 11 12:39:38 kueche volumio[8436]: ModuleNotFoundError: No module named 'camilladsp_plot' May 11 12:39:38 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:38 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:40 kueche systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. May 11 12:39:40 kueche systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 8. May 11 12:39:40 kueche systemd[1]: Stopped FusionDsp Daemon. May 11 12:39:40 kueche systemd[1]: Started FusionDsp Daemon. May 11 12:39:41 kueche volumio[8789]: Traceback (most recent call last): May 11 12:39:41 kueche volumio[8789]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in May 11 12:39:41 kueche volumio[8789]: from camilladsp_plot.validate_config import CamillaValidator May 11 12:39:41 kueche volumio[8789]: ModuleNotFoundError: No module named 'camilladsp_plot' May 11 12:39:41 kueche systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE May 11 12:39:41 kueche systemd[1]: fusiondsp.service: Failed with result 'exit-code'. May 11 12:39:42 kueche volumio[879]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 12:39:42 kueche volumio[879]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES May 11 12:39:42 kueche volumio[879]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 11 12:39:42 kueche volumio[879]: at onErrorNT (internal/child_process.js:465:16) May 11 12:39:42 kueche volumio[879]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 11 12:39:42 kueche volumio[879]: errno: -13, May 11 12:39:42 kueche volumio[879]: code: 'EACCES', May 11 12:39:42 kueche volumio[879]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 11 12:39:42 kueche volumio[879]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 11 12:39:42 kueche volumio[879]: spawnargs: [ May 11 12:39:42 kueche volumio[879]: '-p', May 11 12:39:42 kueche volumio[879]: 9876, May 11 12:39:42 kueche volumio[879]: '-o', May 11 12:39:42 kueche volumio[879]: '/tmp/camilladsp.log', May 11 12:39:42 kueche volumio[879]: '-l', May 11 12:39:42 kueche volumio[879]: 'warn', May 11 12:39:42 kueche volumio[879]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 11 12:39:42 kueche volumio[879]: ] May 11 12:39:42 kueche volumio[879]: } May 11 12:39:42 kueche volumio[879]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 11 12:39:42 kueche sudo[9044]: 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 May 11 12:39:42 kueche sudo[9044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:42 kueche sudo[9044]: pam_unix(sudo:session): session closed for user root May 11 12:39:42 kueche sudo[9047]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params May 11 12:39:42 kueche sudo[9047]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:42 kueche sudo[9047]: pam_unix(sudo:session): session closed for user root May 11 12:39:42 kueche sudo[9051]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc May 11 12:39:42 kueche sudo[9051]: pam_unix(sudo:session): session opened for user root by (uid=0) May 11 12:39:43 kueche sudo[9061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-11 12:38 May 11 12:39:43 kueche sudo[9061]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"