-- Logs begin at Fri 2024-04-26 14:48:03 EEST, end at Fri 2024-04-26 14:51:52 EEST. -- Apr 26 14:50:03 volumio volumio[898]: info: Checking if install.sh is present Apr 26 14:50:03 volumio volumio[898]: info: Executing install.sh Apr 26 14:50:04 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Apr 26 14:50:04 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:50:04 volumio volumio[898]: info: creating filters folder and copying demo filters Apr 26 14:50:04 volumio volumio[898]: info: copying demo flters Apr 26 14:50:04 volumio volumio[898]: info: Installing/fusiondsp dependencies Apr 26 14:50:04 volumio sudo[1561]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Apr 26 14:50:04 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:50:04 volumio volumio[898]: info: Hit:1 http://raspbian.raspberrypi.org/raspbian buster InRelease Apr 26 14:50:04 volumio volumio[898]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease Apr 26 14:50:04 volumio volumio[898]: info: Hit:3 http://archive.raspberrypi.org/debian buster InRelease Apr 26 14:50:05 volumio volumio[898]: info: Reading package lists... Apr 26 14:50:06 volumio volumio[898]: info: Building dependency tree... Apr 26 14:50:06 volumio volumio[898]: info: Reading state information... Apr 26 14:50:06 volumio volumio[898]: info: 25 packages can be upgraded. Run 'apt list --upgradable' to see them. Apr 26 14:50:06 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root Apr 26 14:50:06 volumio sudo[1762]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Apr 26 14:50:06 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:50:06 volumio volumio[898]: info: Reading package lists... Apr 26 14:50:06 volumio volumio[898]: info: Building dependency tree... Apr 26 14:50:06 volumio volumio[898]: info: Reading state information... Apr 26 14:50:07 volumio volumio[898]: info: The following additional packages will be installed: Apr 26 14:50:07 volumio volumio[898]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Apr 26 14:50:07 volumio volumio[898]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Apr 26 14:50:07 volumio volumio[898]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1 libexpat1-dev Apr 26 14:50:07 volumio volumio[898]: info: libfakeroot libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev Apr 26 14:50:07 volumio volumio[898]: info: libpython3.7 libpython3.7-dev libpython3.7-minimal libpython3.7-stdlib Apr 26 14:50:07 volumio volumio[898]: info: libstdc++-8-dev libubsan1 make patch python-pip-whl python3-asn1crypto Apr 26 14:50:07 volumio volumio[898]: info: python3-async-timeout python3-attr python3-cffi-backend python3-crypto Apr 26 14:50:07 volumio volumio[898]: info: python3-cryptography python3-dbus python3-dev python3-entrypoints python3-gi Apr 26 14:50:07 volumio volumio[898]: info: python3-keyring python3-keyrings.alt python3-multidict python3-secretstorage Apr 26 14:50:07 volumio volumio[898]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7 Apr 26 14:50:07 volumio volumio[898]: info: python3.7-dev python3.7-minimal Apr 26 14:50:07 volumio volumio[898]: info: Suggested packages: Apr 26 14:50:07 volumio volumio[898]: info: bzip2-doc cpp-doc gcc-8-locales debian-keyring gcc-8-doc libstdc++6-8-dbg Apr 26 14:50:07 volumio volumio[898]: info: gcc-multilib autoconf automake libtool flex bison gdb gcc-doc libgcc1-dbg Apr 26 14:50:07 volumio volumio[898]: info: libgomp1-dbg libitm1-dbg libatomic1-dbg libasan5-dbg liblsan0-dbg Apr 26 14:50:07 volumio volumio[898]: info: libtsan0-dbg libubsan1-dbg libmpx2-dbg libquadmath0-dbg libstdc++-8-doc Apr 26 14:50:07 volumio volumio[898]: info: make-doc ed diffutils-doc python-attr-doc python-crypto-doc Apr 26 14:50:07 volumio volumio[898]: info: python-cryptography-doc python3-cryptography-vectors python-dbus-doc Apr 26 14:50:07 volumio volumio[898]: info: python3-dbus-dbg gnome-keyring libkf5wallet-bin gir1.2-gnomekeyring-1.0 Apr 26 14:50:07 volumio volumio[898]: info: python-secretstorage-doc python-setuptools-doc python3.7-venv python3.7-doc Apr 26 14:50:07 volumio volumio[898]: info: binfmt-support Apr 26 14:50:07 volumio volumio[898]: info: The following NEW packages will be installed: Apr 26 14:50:07 volumio volumio[898]: info: build-essential bzip2 cpp cpp-8 dh-python dpkg-dev fakeroot g++ g++-8 gcc Apr 26 14:50:07 volumio volumio[898]: info: gcc-8 libalgorithm-diff-perl libalgorithm-diff-xs-perl Apr 26 14:50:07 volumio volumio[898]: info: libalgorithm-merge-perl libasan5 libcc1-0 libexpat1-dev libfakeroot Apr 26 14:50:07 volumio volumio[898]: info: libgcc-8-dev libisl19 libmpc3 libmpfr6 libpython3-dev libpython3.7 Apr 26 14:50:07 volumio volumio[898]: info: libpython3.7-dev libstdc++-8-dev libubsan1 make patch python-pip-whl Apr 26 14:50:07 volumio volumio[898]: info: python3-aiohttp python3-asn1crypto python3-async-timeout python3-attr Apr 26 14:50:07 volumio volumio[898]: info: python3-cffi-backend python3-crypto python3-cryptography python3-dbus Apr 26 14:50:07 volumio volumio[898]: info: python3-dev python3-entrypoints python3-gi python3-keyring Apr 26 14:50:07 volumio volumio[898]: info: python3-keyrings.alt python3-multidict python3-pip python3-secretstorage Apr 26 14:50:07 volumio volumio[898]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Apr 26 14:50:07 volumio volumio[898]: info: The following packages will be upgraded: Apr 26 14:50:07 volumio volumio[898]: info: libexpat1 libpython3.7-minimal libpython3.7-stdlib python3.7 Apr 26 14:50:07 volumio volumio[898]: info: python3.7-minimal Apr 26 14:50:07 volumio volumio[898]: info: 5 upgraded, 51 newly installed, 0 to remove and 22 not upgraded. Apr 26 14:50:07 volumio volumio[898]: info: Need to get 84.3 MB of archives. Apr 26 14:50:07 volumio volumio[898]: info: After this operation, 180 MB of additional disk space will be used. Apr 26 14:50:07 volumio volumio[898]: info: Get:1 http://archive.raspberrypi.org/debian buster/main armhf python-pip-whl all 18.1-5+rpt1 [1591 kB] Apr 26 14:50:07 volumio volumio[898]: info: Get:2 http://mirror.as43289.net/raspbian/raspbian buster/main armhf python3.7 armhf 3.7.3-2+deb10u7 [331 kB] Apr 26 14:50:07 volumio volumio[898]: info: Get:4 http://archive.raspberrypi.org/debian buster/main armhf python3-pip all 18.1-5+rpt1 [171 kB] Apr 26 14:50:07 volumio volumio[898]: info: Get:3 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libpython3.7-stdlib armhf 3.7.3-2+deb10u7 [1665 kB] Apr 26 14:50:07 volumio volumio[898]: info: Get:5 http://mirror.as43289.net/raspbian/raspbian buster/main armhf python3.7-minimal armhf 3.7.3-2+deb10u7 [1201 kB] Apr 26 14:50:07 volumio volumio[898]: info: Get:6 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libpython3.7-minimal armhf 3.7.3-2+deb10u7 [584 kB] Apr 26 14:50:08 volumio volumio[898]: info: Get:7 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libexpat1 armhf 2.2.6-2+deb10u7 [79.7 kB] Apr 26 14:50:08 volumio volumio[898]: info: Get:8 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf bzip2 armhf 1.0.6-9.2~deb10u2 [46.3 kB] Apr 26 14:50:08 volumio volumio[898]: info: Get:9 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libisl19 armhf 0.20-2 [439 kB] Apr 26 14:50:08 volumio volumio[898]: info: Get:10 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libmpfr6 armhf 4.0.2-1 [723 kB] Apr 26 14:50:08 volumio volumio[898]: info: Get:11 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libmpc3 armhf 1.1.0-1 [32.1 kB] Apr 26 14:50:09 volumio volumio[898]: info: Get:12 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf cpp-8 armhf 8.3.0-6+rpi1 [6787 kB] Apr 26 14:50:09 volumio volumio[898]: info: Get:13 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf cpp armhf 4:8.3.0-1+rpi2 [19.6 kB] Apr 26 14:50:09 volumio volumio[898]: info: Get:14 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libcc1-0 armhf 8.3.0-6+rpi1 [37.9 kB] Apr 26 14:50:09 volumio volumio[898]: info: Get:15 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libasan5 armhf 8.3.0-6+rpi1 [317 kB] Apr 26 14:50:09 volumio volumio[898]: info: Get:16 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libubsan1 armhf 8.3.0-6+rpi1 [103 kB] Apr 26 14:50:10 volumio volumio[898]: info: Get:17 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libgcc-8-dev armhf 8.3.0-6+rpi1 [595 kB] Apr 26 14:50:10 volumio volumio[898]: info: Get:18 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf gcc-8 armhf 8.3.0-6+rpi1 [7223 kB] Apr 26 14:50:10 volumio volumio[898]: info: Get:19 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf gcc armhf 4:8.3.0-1+rpi2 [5200 B] Apr 26 14:50:10 volumio volumio[898]: info: Get:20 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libstdc++-8-dev armhf 8.3.0-6+rpi1 [1545 kB] Apr 26 14:50:10 volumio volumio[898]: info: Get:21 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf g++-8 armhf 8.3.0-6+rpi1 [7464 kB] Apr 26 14:50:10 volumio volumio[898]: info: Get:22 http://raspbian.raspberrypi.org/raspbian buster/main armhf g++ armhf 4:8.3.0-1+rpi2 [1632 B] Apr 26 14:50:11 volumio volumio[898]: info: Get:23 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf make armhf 4.2.1-1.2 [321 kB] Apr 26 14:50:11 volumio volumio[898]: info: Get:24 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf patch armhf 2.7.6-3+deb10u1 [115 kB] Apr 26 14:50:11 volumio volumio[898]: info: Get:25 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf dpkg-dev all 1.19.8 [1776 kB] Apr 26 14:50:11 volumio volumio[898]: info: Get:26 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf build-essential armhf 12.6 [7568 B] Apr 26 14:50:11 volumio volumio[898]: info: Get:27 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf dh-python all 3.20190308 [99.3 kB] Apr 26 14:50:12 volumio volumio[898]: info: Get:28 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libfakeroot armhf 1.23-1 [43.3 kB] Apr 26 14:50:12 volumio volumio[898]: info: Get:29 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf fakeroot armhf 1.23-1 [85.2 kB] Apr 26 14:50:12 volumio volumio[898]: info: Get:30 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libalgorithm-diff-perl all 1.19.03-2 [47.9 kB] Apr 26 14:50:12 volumio volumio[898]: info: Get:31 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libalgorithm-diff-xs-perl armhf 0.04-5+b1 [11.4 kB] Apr 26 14:50:12 volumio volumio[898]: info: Get:32 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libalgorithm-merge-perl all 0.08-3 [12.7 kB] Apr 26 14:50:13 volumio volumio[898]: info: Get:33 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libexpat1-dev armhf 2.2.6-2+deb10u7 [130 kB] Apr 26 14:50:13 volumio volumio[898]: info: Get:34 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libpython3.7 armhf 3.7.3-2+deb10u7 [1252 kB] Apr 26 14:50:13 volumio volumio[898]: info: Get:35 http://mirror.as43289.net/raspbian/raspbian buster/main armhf libpython3.7-dev armhf 3.7.3-2+deb10u7 [47.2 MB] Apr 26 14:50:13 volumio volumio[898]: info: Get:36 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf libpython3-dev armhf 3.7.3-1 [20.1 kB] Apr 26 14:50:14 volumio volumio[898]: info: Get:37 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-multidict armhf 4.5.2-1 [52.9 kB] Apr 26 14:50:14 volumio volumio[898]: info: Get:38 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-yarl armhf 1.3.0-1 [44.0 kB] Apr 26 14:50:14 volumio volumio[898]: info: Get:39 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-async-timeout all 3.0.1-1 [6292 B] Apr 26 14:50:14 volumio volumio[898]: info: Get:40 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-attr all 18.2.0-1 [37.3 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:41 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-aiohttp armhf 3.5.1-1+deb10u1 [241 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:42 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-asn1crypto all 0.24.0-1 [78.2 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:43 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-cffi-backend armhf 1.12.2-1 [70.1 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:44 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-crypto armhf 2.6.1-9+b1 [251 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:45 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-cryptography armhf 2.6.1-3+deb10u4 [184 kB] Apr 26 14:50:15 volumio volumio[898]: info: Get:46 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-dbus armhf 1.2.8-3 [95.9 kB] Apr 26 14:50:16 volumio volumio[898]: info: Get:47 http://mirror.as43289.net/raspbian/raspbian buster/main armhf python3.7-dev armhf 3.7.3-2+deb10u7 [527 kB] Apr 26 14:50:16 volumio volumio[898]: info: Get:48 http://raspbian.raspberrypi.org/raspbian buster/main armhf python3-dev armhf 3.7.3-1 [1264 B] Apr 26 14:50:16 volumio volumio[898]: info: Get:49 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-entrypoints all 0.3-1 [5508 B] Apr 26 14:50:16 volumio volumio[898]: info: Get:50 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-gi armhf 3.30.4-1 [161 kB] Apr 26 14:50:16 volumio volumio[898]: info: Get:51 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-secretstorage all 2.3.1-2 [14.2 kB] Apr 26 14:50:17 volumio volumio[898]: info: Get:52 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-keyring all 17.1.1-1 [43.1 kB] Apr 26 14:50:17 volumio volumio[898]: info: Get:53 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-keyrings.alt all 3.1.1-1 [18.2 kB] Apr 26 14:50:17 volumio volumio[898]: info: Get:54 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-setuptools all 40.8.0-1 [306 kB] Apr 26 14:50:17 volumio volumio[898]: info: Get:55 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-wheel all 0.32.3-2 [19.4 kB] Apr 26 14:50:17 volumio volumio[898]: info: Get:56 http://mirrors.pidginhost.com/raspbian/raspbian buster/main armhf python3-xdg all 0.25-5 [35.9 kB] Apr 26 14:50:22 volumio volumio[898]: info: Fetched 84.3 MB in 11s (7756 kB/s) Apr 26 14:50:24 volumio volumio[898]: [620B blob data] Apr 26 14:50:24 volumio volumio[898]: info: Preparing to unpack .../00-python3.7_3.7.3-2+deb10u7_armhf.deb ... Apr 26 14:50:26 volumio volumio[898]: info: Unpacking python3.7 (3.7.3-2+deb10u7) over (3.7.3-2+deb10u6) ... Apr 26 14:51:22 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:22 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:22 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:22 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:22 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:22 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:36 volumio kernel: usb 3-2: USB disconnect, device number 2 Apr 26 14:51:36 volumio thd[631]: Error reading device '/dev/input/event0' Apr 26 14:51:36 volumio volumio[898]: info: No valid Plugin REST Endpoint Apr 26 14:51:36 volumio volumio[898]: info: Apr 26 14:51:36 volumio volumio[898]: ---------------------------- USB Audio Device Detached Apr 26 14:51:36 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 26 14:51:36 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 14:51:38 volumio kernel: usb 3-2: new full-speed USB device number 3 using xhci-hcd Apr 26 14:51:38 volumio kernel: usb 3-2: New USB device found, idVendor=0a12, idProduct=1243, bcdDevice=28.97 Apr 26 14:51:38 volumio kernel: usb 3-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 26 14:51:38 volumio kernel: usb 3-2: Product: FiiO BTR1K Apr 26 14:51:38 volumio kernel: usb 3-2: SerialNumber: ABCDEF0123456789 Apr 26 14:51:39 volumio kernel: input: FiiO BTR1K as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-2/3-2:1.3/0003:0A12:1243.0007/input/input21 Apr 26 14:51:39 volumio kernel: hid-generic 0003:0A12:1243.0007: input,hidraw0: USB HID v1.11 Device [FiiO BTR1K] on usb-xhci-hcd.1-2/input3 Apr 26 14:51:39 volumio kernel: hid-generic 0003:0A12:1243.0008: hiddev96,hidraw1: USB HID v1.11 Device [FiiO BTR1K] on usb-xhci-hcd.1-2/input4 Apr 26 14:51:39 volumio systemd-udevd[1901]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 22. Apr 26 14:51:39 volumio volumio[898]: info: Apr 26 14:51:39 volumio volumio[898]: ---------------------------- USB Audio Device Attached Apr 26 14:51:39 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 26 14:51:39 volumio volumio[898]: info: No valid Plugin REST Endpoint Apr 26 14:51:39 volumio systemd-udevd[1889]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 26 14:51:41 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:41 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:41 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:41 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:41 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:41 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:42 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.100.123 from 192.168.100.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 Apr 26 14:51:42 volumio volumio[898]: info: Received Get System Info Apr 26 14:51:42 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:42 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:42 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:42 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:42 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:42 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:42 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:42 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:44 volumio sudo[1917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 14:51:44 volumio sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:44 volumio sudo[1917]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:44 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 14:51:44 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:44 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:44 volumio volumio[898]: verbose: New Socket.io Connection to 192.168.100.123 from 192.168.100.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 6 Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:44 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 14:51:44 volumio volumio[898]: info: Received Get System Info Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:44 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:44 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:44 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:44 volumio volumio[898]: info: Listing playlists Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 26 14:51:44 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 26 14:51:46 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 14:51:46 volumio volumio[898]: info: Received Get System Info Apr 26 14:51:46 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:46 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:46 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:46 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:46 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:46 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:47 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 26 14:51:47 volumio volumio[898]: info: Received Get System Info Apr 26 14:51:47 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 14:51:47 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 14:51:47 volumio volumio[898]: info: Discovery: Getting this device information Apr 26 14:51:47 volumio volumio[898]: info: CoreCommandRouter::volumioGetState Apr 26 14:51:47 volumio volumio[898]: info: CorePlayQueue::getTrack 0 Apr 26 14:51:47 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:50 volumio volumio[898]: info: Enabling plugin fusiondsp Apr 26 14:51:50 volumio volumio[898]: info: Loading plugin "fusiondsp"... Apr 26 14:51:50 volumio volumio[898]: info: Preparing to generate the ALSA configuration file Apr 26 14:51:50 volumio volumio[898]: info: Asound.conf file unchanged, so no further update is needed Apr 26 14:51:50 volumio volumio[898]: info: Output device has changed, restarting MPD Apr 26 14:51:50 volumio sudo[1928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 14:51:50 volumio sudo[1928]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio volumio[898]: info: Output device has changed, restarting Shairport Sync Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 14:51:50 volumio sudo[1928]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 14:51:50 volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 14:51:50 volumio volumio[898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 14:51:50 volumio volumio[898]: info: PLUGIN START: fusiondsp Apr 26 14:51:50 volumio systemd[1]: mpd.service: Succeeded. Apr 26 14:51:50 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 14:51:50 volumio volumio[898]: info: Loading i18n strings for locale en Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: FusionDsp - mixtype--------------------- None Apr 26 14:51:50 volumio volumio[898]: info: Preparing to generate the ALSA configuration file Apr 26 14:51:50 volumio volumio[898]: info: Done. Apr 26 14:51:50 volumio volumio[898]: info: MPD Permissions set Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: Asound.conf file unchanged, so no further update is needed Apr 26 14:51:50 volumio volumio[898]: info: Output device has changed, restarting MPD Apr 26 14:51:50 volumio systemd[1]: Starting Music Player Daemon... Apr 26 14:51:50 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 14:51:50 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio volumio[898]: info: Output device has changed, restarting Shairport Sync Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 14:51:50 volumio sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 14:51:50 volumio sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 26 14:51:50 volumio systemd[1]: mpd.service: Succeeded. Apr 26 14:51:50 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 14:51:50 volumio systemd[1]: Starting Music Player Daemon... Apr 26 14:51:50 volumio volumio[898]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 14:51:50 volumio volumio[898]: info: Starting Shairport Sync Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio sudo[1952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 14:51:50 volumio sudo[1952]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:50 volumio volumio[898]: info: Starting Shairport Sync Apr 26 14:51:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 14:51:50 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 14:51:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 14:51:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 14:51:50 volumio sudo[1952]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio sudo[1960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 26 14:51:50 volumio sudo[1960]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:50 volumio volumio[898]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 14:51:50 volumio volumio[898]: info: Shairport-Sync Started Apr 26 14:51:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 26 14:51:50 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 26 14:51:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 26 14:51:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 26 14:51:50 volumio sudo[1960]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio volumio[898]: info: Shairport-Sync Started Apr 26 14:51:50 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio mpd[1950]: Apr 26 14:51 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 14:51:50 volumio systemd[1]: Started Music Player Daemon. Apr 26 14:51:50 volumio sudo[1944]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio sudo[1930]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:50 volumio volumio[898]: info: MPD Permissions set Apr 26 14:51:50 volumio volumio[898]: error: MPD error: The expression evaluated to a falsy value: Apr 26 14:51:50 volumio volumio[898]: assert.ok(self.idling) Apr 26 14:51:50 volumio volumio[898]: error: The expression evaluated to a falsy value: Apr 26 14:51:50 volumio volumio[898]: assert.ok(self.idling) Apr 26 14:51:50 volumio volumio[898]: error: updateQueue error: null Apr 26 14:51:52 volumio volumio[898]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Apr 26 14:51:52 volumio volumio[898]: info: camilladsp service started and running in background, instance 1 Apr 26 14:51:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 14:51:52 volumio volumio[898]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 26 14:51:52 volumio volumio[898]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Apr 26 14:51:52 volumio volumio[898]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Apr 26 14:51:52 volumio volumio[898]: info: FusionDsp loaded Apr 26 14:51:52 volumio volumio[898]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 14:51:52 volumio sudo[1979]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 26 14:51:52 volumio sudo[1979]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 14:51:52 volumio volumio[898]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 26 14:51:52 volumio volumio[898]: info: Adding Signal Path Element [object Object] Apr 26 14:51:52 volumio volumio[898]: info: Adding fusiondspeq DSP Signal Path Element Apr 26 14:51:52 volumio volumio[898]: info: FusionDsp - ---- installed callbackRead Apr 26 14:51:52 volumio volumio[898]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 14:51:52 volumio sudo[1979]: pam_unix(sudo:session): session closed for user root Apr 26 14:51:52 volumio volumio[898]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 26 14:51:52 volumio volumio[898]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Apr 26 14:51:52 volumio volumio[898]: at onErrorNT (internal/child_process.js:465:16) Apr 26 14:51:52 volumio volumio[898]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Apr 26 14:51:52 volumio volumio[898]: at runNextTicks (internal/process/task_queues.js:62:3) Apr 26 14:51:52 volumio volumio[898]: at listOnTimeout (internal/timers.js:523:9) Apr 26 14:51:52 volumio volumio[898]: at processTimers (internal/timers.js:497:7) { Apr 26 14:51:52 volumio volumio[898]: errno: -2, Apr 26 14:51:52 volumio volumio[898]: code: 'ENOENT', Apr 26 14:51:52 volumio volumio[898]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 26 14:51:52 volumio volumio[898]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 26 14:51:52 volumio volumio[898]: spawnargs: [ Apr 26 14:51:52 volumio volumio[898]: '-p', Apr 26 14:51:52 volumio volumio[898]: 9876, Apr 26 14:51:52 volumio volumio[898]: '-o', Apr 26 14:51:52 volumio volumio[898]: '/tmp/camilladsp.log', Apr 26 14:51:52 volumio volumio[898]: '-l', Apr 26 14:51:52 volumio volumio[898]: 'warn', Apr 26 14:51:52 volumio volumio[898]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 26 14:51:52 volumio volumio[898]: ] Apr 26 14:51:52 volumio volumio[898]: } Apr 26 14:51:52 volumio volumio[898]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 14:51:52 volumio sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 14:50 Apr 26 14:51:52 volumio sudo[1990]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"