Dec 06 21:58:13 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 21:58:19 volumio volumio[1614]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/peppyspectrum/1.0.39/volumio/bookworm/armhf Dec 06 21:58:21 volumio volumio[1614]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/download/peppyspectrum/1.0.39/volumio/bookworm/armhf Dec 06 21:58:21 volumio volumio[1614]: info: Folder /tmp/plugins removed Dec 06 21:58:21 volumio volumio[1614]: info: Check plugin dependencies Dec 06 21:58:21 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 06 21:58:21 volumio volumio[1614]: info: Checking if plugin already exists Dec 06 21:58:21 volumio volumio[1614]: info: Rename folder Dec 06 21:58:21 volumio volumio[1614]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 21:58:21 volumio volumio[1614]: info: Move to category Dec 06 21:58:22 volumio volumio[1614]: info: Checking if install.sh is present Dec 06 21:58:22 volumio volumio[1614]: info: Executing install.sh Dec 06 21:58:22 volumio sudo[4395]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh Dec 06 21:58:22 volumio sudo[4395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:22 volumio volumio[1614]: info: Installing peppyalsa plugin dependencies Dec 06 21:58:22 volumio sudo[4399]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/usermod -aG render volumio Dec 06 21:58:22 volumio sudo[4399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:22 volumio sudo[4399]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:22 volumio sudo[4404]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Dec 06 21:58:22 volumio sudo[4404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:22 volumio volumio[1614]: info: Hit:1 https://deb.nodesource.com/node_20.x nodistro InRelease Dec 06 21:58:22 volumio volumio[1614]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Dec 06 21:58:22 volumio volumio[1614]: info: Hit:3 http://archive.raspberrypi.com/debian bookworm InRelease Dec 06 21:58:24 volumio volumio[1614]: info: Reading package lists... Dec 06 21:58:24 volumio sudo[4404]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:24 volumio sudo[4595]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 06 21:58:24 volumio sudo[4595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:24 volumio sudo[4595]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:24 volumio sudo[4597]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Dec 06 21:58:24 volumio sudo[4597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:24 volumio sudo[4597]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:24 volumio volumio[1614]: info: installing apt packages Dec 06 21:58:24 volumio sudo[4599]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev Dec 06 21:58:24 volumio sudo[4599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:24 volumio volumio[1614]: info: Reading package lists... Dec 06 21:58:25 volumio volumio[1614]: info: Building dependency tree... Dec 06 21:58:25 volumio volumio[1614]: info: Reading state information... Dec 06 21:58:25 volumio volumio[1614]: info: The following additional packages will be installed: Dec 06 21:58:25 volumio volumio[1614]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-double3 libimagequant0 Dec 06 21:58:25 volumio volumio[1614]: info: libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 libsdl2-mixer-2.0-0 Dec 06 21:58:25 volumio volumio[1614]: info: libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy python3-olefile Dec 06 21:58:25 volumio volumio[1614]: info: Suggested packages: Dec 06 21:58:25 volumio volumio[1614]: info: bzip2-doc libfftw3-doc gcc gfortran python3-dev python3-pytest Dec 06 21:58:25 volumio volumio[1614]: info: python-pil-doc python-pygame-doc timidity Dec 06 21:58:25 volumio volumio[1614]: info: The following NEW packages will be installed: Dec 06 21:58:25 volumio volumio[1614]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-dev libfftw3-double3 Dec 06 21:58:25 volumio volumio[1614]: info: libimagequant0 libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 Dec 06 21:58:25 volumio volumio[1614]: info: libsdl2-mixer-2.0-0 libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy Dec 06 21:58:25 volumio volumio[1614]: info: python3-olefile python3-pil python3-pygame Dec 06 21:58:26 volumio volumio[1614]: info: 0 upgraded, 19 newly installed, 0 to remove and 0 not upgraded. Dec 06 21:58:26 volumio volumio[1614]: info: Need to get 9480 kB of archives. Dec 06 21:58:26 volumio volumio[1614]: info: After this operation, 45.6 MB of additional disk space will be used. Dec 06 21:58:26 volumio volumio[1614]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf bzip2 armhf 1.0.8-5+b2 [47.4 kB] Dec 06 21:58:27 volumio volumio[1614]: info: Get:2 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf file armhf 1:5.44-3 [41.5 kB] Dec 06 21:58:27 volumio volumio[1614]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mailcap all 3.70+nmu1 [32.0 kB] Dec 06 21:58:27 volumio volumio[1614]: info: Get:4 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf mime-support all 3.66 [10.9 kB] Dec 06 21:58:28 volumio volumio[1614]: info: Get:5 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf fonts-freefont-ttf all 20120503-10 [2552 kB] Dec 06 21:58:29 volumio volumio[1614]: info: Get:6 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libfftw3-double3 armhf 3.3.10-1 [325 kB] Dec 06 21:58:29 volumio volumio[1614]: info: Get:7 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libfftw3-bin armhf 3.3.10-1 [40.8 kB] Dec 06 21:58:29 volumio volumio[1614]: info: Get:8 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libfftw3-dev armhf 3.3.10-1 [827 kB] Dec 06 21:58:30 volumio volumio[1614]: info: Get:9 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libimagequant0 armhf 2.17.0-1 [28.0 kB] Dec 06 21:58:30 volumio volumio[1614]: info: Get:10 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libopusfile0 armhf 0.12-4 [38.2 kB] Dec 06 21:58:30 volumio volumio[1614]: info: Get:11 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libportmidi0 armhf 1:217-6.1 [20.1 kB] Dec 06 21:58:30 volumio volumio[1614]: info: Get:12 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] Dec 06 21:58:31 volumio volumio[1614]: info: Get:13 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-image-2.0-0 armhf 2.6.3+dfsg-1 [54.6 kB] Dec 06 21:58:31 volumio volumio[1614]: info: Get:14 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-mixer-2.0-0 armhf 2.6.2+dfsg-2 [69.2 kB] Dec 06 21:58:31 volumio volumio[1614]: info: Get:15 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-ttf-2.0-0 armhf 2.20.1+dfsg-2 [26.2 kB] Dec 06 21:58:32 volumio volumio[1614]: info: Get:16 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-numpy armhf 1:1.24.2-1+deb12u1 [3827 kB] Dec 06 21:58:33 volumio volumio[1614]: info: Get:17 http://raspbian.mirror.net.in/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] Dec 06 21:58:33 volumio volumio[1614]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] Dec 06 21:58:33 volumio volumio[1614]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pygame armhf 2.1.2+dfsg-5 [1061 kB] Dec 06 21:58:34 volumio volumio[1614]: info: Fetched 9480 kB in 8s (1222 kB/s) Dec 06 21:58:34 volumio volumio[1614]: info: Selecting previously unselected package bzip2. Dec 06 21:58:34 volumio volumio[1614]: [620B blob data] Dec 06 21:58:34 volumio volumio[1614]: info: Preparing to unpack .../00-bzip2_1.0.8-5+b2_armhf.deb ... Dec 06 21:58:34 volumio volumio[1614]: info: Unpacking bzip2 (1.0.8-5+b2) ... Dec 06 21:58:34 volumio volumio[1614]: info: Selecting previously unselected package file. Dec 06 21:58:34 volumio volumio[1614]: info: Preparing to unpack .../01-file_1%3a5.44-3_armhf.deb ... Dec 06 21:58:34 volumio volumio[1614]: info: Unpacking file (1:5.44-3) ... Dec 06 21:58:34 volumio volumio[1614]: info: Selecting previously unselected package mailcap. Dec 06 21:58:34 volumio volumio[1614]: info: Preparing to unpack .../02-mailcap_3.70+nmu1_all.deb ... Dec 06 21:58:34 volumio volumio[1614]: info: Unpacking mailcap (3.70+nmu1) ... Dec 06 21:58:34 volumio volumio[1614]: info: Selecting previously unselected package mime-support. Dec 06 21:58:34 volumio volumio[1614]: info: Preparing to unpack .../03-mime-support_3.66_all.deb ... Dec 06 21:58:34 volumio volumio[1614]: info: Unpacking mime-support (3.66) ... Dec 06 21:58:34 volumio volumio[1614]: info: Selecting previously unselected package fonts-freefont-ttf. Dec 06 21:58:34 volumio volumio[1614]: info: Preparing to unpack .../04-fonts-freefont-ttf_20120503-10_all.deb ... Dec 06 21:58:34 volumio volumio[1614]: info: Unpacking fonts-freefont-ttf (20120503-10) ... Dec 06 21:58:35 volumio volumio[1614]: info: Selecting previously unselected package libfftw3-double3:armhf. Dec 06 21:58:35 volumio volumio[1614]: info: Preparing to unpack .../05-libfftw3-double3_3.3.10-1_armhf.deb ... Dec 06 21:58:35 volumio volumio[1614]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Dec 06 21:58:35 volumio volumio[1614]: info: Selecting previously unselected package libfftw3-bin. Dec 06 21:58:35 volumio volumio[1614]: info: Preparing to unpack .../06-libfftw3-bin_3.3.10-1_armhf.deb ... Dec 06 21:58:35 volumio volumio[1614]: info: Unpacking libfftw3-bin (3.3.10-1) ... Dec 06 21:58:35 volumio volumio[1614]: info: Selecting previously unselected package libfftw3-dev:armhf. Dec 06 21:58:35 volumio volumio[1614]: info: Preparing to unpack .../07-libfftw3-dev_3.3.10-1_armhf.deb ... Dec 06 21:58:35 volumio volumio[1614]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libimagequant0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../08-libimagequant0_2.17.0-1_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libopusfile0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../09-libopusfile0_0.12-4_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libopusfile0:armhf (0.12-4) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libportmidi0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../10-libportmidi0_1%3a217-6.1_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libraqm0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../11-libraqm0_0.7.0-4.1_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../12-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Dec 06 21:58:36 volumio volumio[1614]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. Dec 06 21:58:36 volumio volumio[1614]: info: Preparing to unpack .../13-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... Dec 06 21:58:36 volumio volumio[1614]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Dec 06 21:58:37 volumio volumio[1614]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. Dec 06 21:58:37 volumio volumio[1614]: info: Preparing to unpack .../14-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... Dec 06 21:58:37 volumio volumio[1614]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Dec 06 21:58:37 volumio volumio[1614]: info: Selecting previously unselected package python3-numpy. Dec 06 21:58:37 volumio volumio[1614]: info: Preparing to unpack .../15-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... Dec 06 21:58:37 volumio volumio[1614]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... Dec 06 21:58:44 volumio volumio[1614]: info: Selecting previously unselected package python3-olefile. Dec 06 21:58:44 volumio volumio[1614]: info: Preparing to unpack .../16-python3-olefile_0.46-3_all.deb ... Dec 06 21:58:44 volumio volumio[1614]: info: Unpacking python3-olefile (0.46-3) ... Dec 06 21:58:44 volumio volumio[1614]: info: Selecting previously unselected package python3-pil:armhf. Dec 06 21:58:44 volumio volumio[1614]: info: Preparing to unpack .../17-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... Dec 06 21:58:44 volumio volumio[1614]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 06 21:58:45 volumio volumio[1614]: info: Selecting previously unselected package python3-pygame. Dec 06 21:58:45 volumio volumio[1614]: info: Preparing to unpack .../18-python3-pygame_2.1.2+dfsg-5_armhf.deb ... Dec 06 21:58:45 volumio volumio[1614]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... Dec 06 21:58:47 volumio volumio[1614]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Dec 06 21:58:47 volumio volumio[1614]: info: Setting up python3-olefile (0.46-3) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up fonts-freefont-ttf (20120503-10) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up file (1:5.44-3) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up bzip2 (1.0.8-5+b2) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up libportmidi0:armhf (1:217-6.1) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up libimagequant0:armhf (2.17.0-1) ... Dec 06 21:58:48 volumio volumio[1614]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up mailcap (3.70+nmu1) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up libopusfile0:armhf (0.12-4) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up mime-support (3.66) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up libfftw3-bin (3.3.10-1) ... Dec 06 21:58:51 volumio volumio[1614]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... Dec 06 21:58:52 volumio volumio[1614]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Dec 06 21:58:52 volumio volumio[1614]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... Dec 06 21:58:52 volumio volumio[1614]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... Dec 06 21:58:52 volumio volumio[1614]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 06 21:58:53 volumio volumio[1614]: info: Processing triggers for man-db (2.11.2-2) ... Dec 06 21:58:53 volumio volumio[1614]: info: Processing triggers for fontconfig (2.14.1-4) ... Dec 06 21:58:53 volumio sudo[4599]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:53 volumio volumio[1614]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so Dec 06 21:58:53 volumio volumio[1614]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so.0 Dec 06 21:58:53 volumio sudo[4906]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Dec 06 21:58:53 volumio sudo[4906]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:53 volumio sudo[4906]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:53 volumio sudo[4908]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 06 21:58:53 volumio sudo[4908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:53 volumio systemd[1]: Reloading. Dec 06 21:58:54 volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Dec 06 21:58:54 volumio sudo[4908]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:54 volumio sudo[4395]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:54 volumio volumio[1614]: Plugin install end detected on script Dec 06 21:58:54 volumio volumio[1614]: info: Install script completed Dec 06 21:58:54 volumio volumio[1614]: info: Adding reference to registry Dec 06 21:58:54 volumio volumio[1614]: info: Done installing plugin. Dec 06 21:58:54 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 06 21:58:54 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 06 21:58:54 volumio volumio[1614]: info: Folder /tmp/plugins removed Dec 06 21:58:54 volumio volumio[1614]: info: Folder /tmp/downloaded_plugin.zip removed Dec 06 21:58:54 volumio volumio[1614]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 21:58:54 volumio volumio[1614]: info: Folder /data/temp removed Dec 06 21:58:57 volumio volumio[1614]: info: Enabling plugin peppyspectrum Dec 06 21:58:57 volumio volumio[1614]: info: Loading plugin "peppyspectrum"... Dec 06 21:58:57 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:58:57 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:58:57 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:58:57 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:58:57 volumio volumio[1614]: info: Asound.conf file written Dec 06 21:58:57 volumio sudo[4944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 06 21:58:57 volumio sudo[4944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio sudo[4944]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:57 volumio volumio[1614]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 06 21:58:57 volumio volumio[1614]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 06 21:58:57 volumio volumio[1614]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 06 21:58:57 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:58:57 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:58:57 volumio sudo[4952]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:58:57 volumio sudo[4952]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio sudo[4950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:58:57 volumio sudo[4950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio sudo[4950]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:57 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:58:57 volumio volumio[1614]: info: PLUGIN START: peppyspectrum Dec 06 21:58:57 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 06 21:58:57 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:58:57 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:58:57 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 06 21:58:57 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:58:57 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:58:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:58:57 volumio systemd[1]: mpd.service: Consumed 3.041s CPU time. Dec 06 21:58:57 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:58:57 volumio sudo[4960]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 06 21:58:57 volumio sudo[4960]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:58:57 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:58:57 volumio volumio[1614]: info: Done. Dec 06 21:58:57 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:58:57 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:58:57 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:58:57 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:58:57 volumio volumio[1614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio sudo[4960]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:57 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:58:57 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: snd_dummy loaded Dec 06 21:58:57 volumio (udev-worker)[4974]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 06 21:58:57 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:58:57 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:58:57 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::volumioPause Dec 06 21:58:57 volumio volumio[1614]: info: CoreStateMachine::pause Dec 06 21:58:57 volumio volumio[1614]: info: CoreStateMachine::stPlaybackTimer Dec 06 21:58:57 volumio volumio[1614]: info: CoreStateMachine::servicePause Dec 06 21:58:57 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::servicePause Dec 06 21:58:57 volumio volumio[1614]: info: ControllerMpd::pause Dec 06 21:58:57 volumio volumio[1614]: verbose: ControllerMpd::sendMpdCommand pause Dec 06 21:58:57 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:58:57 volumio volumio[1614]: info: Asound.conf file unchanged, so no further update is needed Dec 06 21:58:57 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:58:57 volumio sudo[4977]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:58:57 volumio sudo[4977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:58:57 volumio sudo[4972]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:58:57 volumio sudo[4972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:57 volumio sudo[4979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:58:57 volumio sudo[4979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio sudo[4972]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:57 volumio sudo[4979]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:57 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:58:57 volumio sudo[4981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:58:57 volumio sudo[4981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:58:57 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:58:57 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:58:57 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:58:57 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:58:57 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:58:57 volumio sudo[5000]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:58:57 volumio sudo[5000]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:58:57 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:58:57 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:58:57 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:58:57 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:58:57 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:58:57 volumio systemd[1]: shairport-sync.service: Consumed 2.348s CPU time. Dec 06 21:58:58 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:58:58 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:58:58 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:58:58 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:58:58 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:58:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:58:58 volumio sudo[4977]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:58 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:58:58 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:58:58 volumio sudo[5003]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:58:58 volumio sudo[5003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:58:58 volumio sudo[5003]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:58 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:58:58 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:58:58 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:58:58 volumio sudo[5000]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:58 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:58:59 volumio mpd[5006]: 2025-12-06T21:58:59 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:58:59 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:58:59 volumio sudo[4952]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:59 volumio sudo[4981]: pam_unix(sudo:session): session closed for user root Dec 06 21:58:59 volumio volumio[1614]: error: MPD error: The expression evaluated to a falsy value: Dec 06 21:58:59 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:58:59 volumio volumio[1614]: error: The expression evaluated to a falsy value: Dec 06 21:58:59 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:58:59 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:58:59 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:00 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:00 volumio sudo[5040]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:00 volumio sudo[5040]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:00 volumio systemd[1]: musicservicesshield.service: Main process exited, code=killed, status=15/TERM Dec 06 21:59:00 volumio systemd[1]: musicservicesshield.service: Failed with result 'signal'. Dec 06 21:59:00 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:00 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:59:00 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:00 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:00 volumio sudo[5045]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:00 volumio sudo[5045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:00 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:00 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:00 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:00 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:00 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:00 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:00 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:00 volumio sudo[5050]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:00 volumio sudo[5050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:00 volumio sudo[5050]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:00 volumio mpd[5052]: 2025-12-06T21:59:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:59:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:59:00 volumio sudo[5040]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:00 volumio sudo[5045]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:00 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:00 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:00 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:01 volumio bash[5069]: cset: --> shielding system active with Dec 06 21:59:01 volumio bash[5069]: cset: "system" cpuset of CPUSPEC(1-3) with 251 tasks running Dec 06 21:59:01 volumio bash[5069]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 06 21:59:01 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:01 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:01 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:01 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:01 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:02 volumio volumio[1614]: info: Enabling plugin peppyspectrum Dec 06 21:59:02 volumio volumio[1614]: info: Loading plugin "peppyspectrum"... Dec 06 21:59:02 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:59:02 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:59:02 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:59:02 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:59:02 volumio volumio[1614]: info: Asound.conf file unchanged, so no further update is needed Dec 06 21:59:02 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:59:02 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:02 volumio sudo[5074]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:59:02 volumio sudo[5074]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio sudo[5074]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio sudo[5076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:02 volumio sudo[5076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:59:02 volumio volumio[1614]: info: PLUGIN START: peppyspectrum Dec 06 21:59:02 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:02 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:02 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 06 21:59:02 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:59:02 volumio sudo[5084]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 06 21:59:02 volumio sudo[5084]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:02 volumio volumio[1614]: info: Done. Dec 06 21:59:02 volumio sudo[5084]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:59:02 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:59:02 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:59:02 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:59:02 volumio volumio[1614]: info: snd_dummy loaded Dec 06 21:59:02 volumio volumio[1614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:59:02 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:59:02 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:59:02 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:59:02 volumio volumio[1614]: info: Asound.conf file unchanged, so no further update is needed Dec 06 21:59:02 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:59:02 volumio sudo[5095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:59:02 volumio sudo[5095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio sudo[5097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:59:02 volumio sudo[5097]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio sudo[5097]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:02 volumio sudo[5101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:02 volumio sudo[5101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:59:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:02 volumio systemd[1]: shairport-sync.service: Consumed 1.525s CPU time. Dec 06 21:59:02 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:59:02 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:59:02 volumio sudo[5093]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:02 volumio sudo[5093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio sudo[5093]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:02 volumio sudo[5095]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:02 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:59:02 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:02 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:02 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:02 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:02 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:59:02 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:02 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:59:02 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:59:02 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:59:02 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:59:02 volumio sudo[5134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:59:02 volumio sudo[5134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:59:02 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:02 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:02 volumio sudo[5134]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:59:02 volumio sudo[5147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:02 volumio sudo[5147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio sudo[5131]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:02 volumio sudo[5131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:02 volumio sudo[5131]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:02 volumio sudo[5156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:02 volumio sudo[5156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:02 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:02 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:02 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:03 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:03 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:03 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:03 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:03 volumio mpd[5158]: 2025-12-06T21:59:03 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:59:03 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:59:03 volumio sudo[5076]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio sudo[5101]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:03 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:03 volumio sudo[5147]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio sudo[5156]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio mpd_monitor.sh[5162]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:03 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:03 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:03 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:03 volumio sudo[5182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:03 volumio sudo[5182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:03 volumio sudo[5184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:03 volumio sudo[5184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:03 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:03 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:03 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:03 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:03 volumio sudo[5182]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio mpd_monitor.sh[5187]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:03 volumio sudo[5184]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:03 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:03 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:03 volumio bash[5180]: cset: --> shielding system active with Dec 06 21:59:03 volumio bash[5180]: cset: "system" cpuset of CPUSPEC(1-3) with 248 tasks running Dec 06 21:59:03 volumio bash[5180]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 06 21:59:03 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:04 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:04 volumio sudo[5206]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:04 volumio sudo[5206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:04 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 06 21:59:04 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:04 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:59:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:04 volumio sudo[5209]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:04 volumio sudo[5209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:04 volumio sudo[5209]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:04 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:04 volumio sudo[5213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:04 volumio sudo[5213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:04 volumio sudo[5216]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:04 volumio sudo[5216]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:04 volumio sudo[5216]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:05 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:05 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:05 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:05 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:05 volumio mpd[5218]: 2025-12-06T21:59:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:59:05 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:59:05 volumio sudo[5213]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:05 volumio sudo[5206]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:05 volumio volumio[1614]: error: MPD error: The expression evaluated to a falsy value: Dec 06 21:59:05 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:05 volumio volumio[1614]: error: The expression evaluated to a falsy value: Dec 06 21:59:05 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:05 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:05 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:05 volumio bash[5235]: cset: --> shielding system active with Dec 06 21:59:06 volumio bash[5235]: cset: "system" cpuset of CPUSPEC(1-3) with 247 tasks running Dec 06 21:59:06 volumio bash[5235]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 06 21:59:06 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:06 volumio sudo[5237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:06 volumio sudo[5237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:06 volumio sudo[5239]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:06 volumio sudo[5239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:06 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:06 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:06 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:06 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:06 volumio mpd_monitor.sh[5242]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:06 volumio sudo[5239]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:06 volumio sudo[5237]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:06 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:06 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:07 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:07 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:07 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:07 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:08 volumio volumio[1614]: info: Enabling plugin peppyspectrum Dec 06 21:59:08 volumio volumio[1614]: info: Loading plugin "peppyspectrum"... Dec 06 21:59:08 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:59:08 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:59:08 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:59:08 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:59:08 volumio volumio[1614]: info: Asound.conf file unchanged, so no further update is needed Dec 06 21:59:08 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:59:08 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:08 volumio sudo[5247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:59:08 volumio sudo[5247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio sudo[5247]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio sudo[5251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:08 volumio sudo[5251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:59:08 volumio volumio[1614]: info: PLUGIN START: peppyspectrum Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 06 21:59:08 volumio volumio[1614]: info: Preparing to generate the ALSA configuration file Dec 06 21:59:08 volumio volumio[1614]: info: Done. Dec 06 21:59:08 volumio sudo[5257]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Dec 06 21:59:08 volumio sudo[5257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:08 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:08 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:59:08 volumio volumio[1614]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 06 21:59:08 volumio volumio[1614]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 06 21:59:08 volumio volumio[1614]: info: Reading ALSA contributions from plugins. Dec 06 21:59:08 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:59:08 volumio sudo[5257]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio volumio[1614]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:08 volumio volumio[1614]: info: snd_dummy loaded Dec 06 21:59:08 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:59:08 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:59:08 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:59:08 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:59:08 volumio volumio[1614]: info: Asound.conf file unchanged, so no further update is needed Dec 06 21:59:08 volumio volumio[1614]: info: Output device has changed, restarting MPD Dec 06 21:59:08 volumio sudo[5266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:59:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:08 volumio sudo[5266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:08 volumio volumio[1614]: info: Output device has changed, restarting Shairport Sync Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:08 volumio sudo[5268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 06 21:59:08 volumio sudo[5268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:59:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:08 volumio systemd[1]: shairport-sync.service: Consumed 1.497s CPU time. Dec 06 21:59:08 volumio volumio[1614]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 06 21:59:08 volumio sudo[5268]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:08 volumio sudo[5275]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:08 volumio sudo[5275]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio sudo[5266]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio volumio[1614]: info: MPD Permissions set Dec 06 21:59:08 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 06 21:59:08 volumio volumio[1614]: info: Starting Shairport Sync Dec 06 21:59:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:08 volumio volumio[1614]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 06 21:59:08 volumio volumio[1614]: info: CoreCommandRouter::volumioGetState Dec 06 21:59:08 volumio volumio[1614]: info: CorePlayQueue::getTrack 0 Dec 06 21:59:08 volumio volumio[1614]: info: mpdhttpout --- Volumio set on pause Dec 06 21:59:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:08 volumio sudo[5304]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 06 21:59:08 volumio sudo[5304]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 06 21:59:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 06 21:59:08 volumio sudo[5304]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio volumio[1614]: info: Shairport-Sync Started Dec 06 21:59:08 volumio sudo[5305]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:08 volumio sudo[5305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:08 volumio sudo[5305]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:08 volumio sudo[5325]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:08 volumio sudo[5325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio sudo[5327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:08 volumio sudo[5327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:08 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:08 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:08 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:09 volumio mpd[5323]: 2025-12-06T21:59:09 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:59:09 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:59:09 volumio sudo[5275]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:09 volumio sudo[5251]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:09 volumio volumio[1614]: error: MPD error: The expression evaluated to a falsy value: Dec 06 21:59:09 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:09 volumio volumio[1614]: error: The expression evaluated to a falsy value: Dec 06 21:59:09 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:09 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:09 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:09 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:09 volumio sudo[5327]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:09 volumio mpd_monitor.sh[5332]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:09 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:09 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:09 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:09 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:09 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:09 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:09 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:09 volumio bash[5350]: cset: --> shielding system active with Dec 06 21:59:09 volumio bash[5350]: cset: "system" cpuset of CPUSPEC(1-3) with 247 tasks running Dec 06 21:59:09 volumio bash[5350]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 06 21:59:09 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:10 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:10 volumio sudo[5352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:10 volumio sudo[5352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:10 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 06 21:59:10 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:10 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 06 21:59:10 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:10 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 06 21:59:10 volumio sudo[5357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 06 21:59:10 volumio sudo[5357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:10 volumio sudo[5355]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:10 volumio sudo[5355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:10 volumio sudo[5355]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:10 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 06 21:59:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 06 21:59:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 06 21:59:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 06 21:59:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 06 21:59:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 06 21:59:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 06 21:59:10 volumio sudo[5362]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 06 21:59:10 volumio sudo[5362]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 06 21:59:10 volumio sudo[5362]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:10 volumio volumio[1614]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 06 21:59:11 volumio mpd[5364]: 2025-12-06T21:59:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 06 21:59:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 06 21:59:11 volumio sudo[5352]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:11 volumio sudo[5357]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:11 volumio volumio[1614]: error: MPD error: The expression evaluated to a falsy value: Dec 06 21:59:11 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:11 volumio volumio[1614]: error: The expression evaluated to a falsy value: Dec 06 21:59:11 volumio volumio[1614]: assert.ok(self.idling) Dec 06 21:59:11 volumio volumio[1614]: error: updateQueue error: null Dec 06 21:59:11 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 06 21:59:11 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:11 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:11 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:11 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:11 volumio volumio[1614]: info: CoreCommandRouter::volumioPause Dec 06 21:59:11 volumio volumio[1614]: info: CoreStateMachine::pause Dec 06 21:59:11 volumio bash[5381]: cset: --> shielding system active with Dec 06 21:59:11 volumio bash[5381]: cset: "system" cpuset of CPUSPEC(1-3) with 246 tasks running Dec 06 21:59:11 volumio bash[5381]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 06 21:59:11 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 06 21:59:12 volumio sudo[5383]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:12 volumio sudo[5383]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:12 volumio sudo[5385]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:12 volumio sudo[5385]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:12 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:12 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:12 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:12 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:12 volumio sudo[5383]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:12 volumio sudo[5385]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:12 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:12 volumio mpd_monitor.sh[5388]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:12 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:13 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:13 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:13 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:13 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:14 volumio sudo[5393]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:14 volumio sudo[5393]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:14 volumio sudo[5395]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 06 21:59:14 volumio sudo[5395]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 06 21:59:14 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 06 21:59:14 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 06 21:59:14 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:14 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 06 21:59:14 volumio sudo[5395]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:14 volumio sudo[5393]: pam_unix(sudo:session): session closed for user root Dec 06 21:59:14 volumio mpd_monitor.sh[5398]: MPD Monitor Service: Starting MPD Monitor Service Dec 06 21:59:14 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:14 volumio volumio[1614]: info: Successfully started MPD Monitor Dec 06 21:59:15 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:15 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:15 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:15 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:15 volumio volumio[1614]: info: CoreCommandRouter::volumioPause Dec 06 21:59:15 volumio volumio[1614]: info: CoreStateMachine::pause Dec 06 21:59:16 volumio volumio[1614]: info: CoreCommandRouter::volumioPause Dec 06 21:59:16 volumio volumio[1614]: info: CoreStateMachine::pause Dec 06 21:59:17 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:17 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:17 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:17 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:19 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:19 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:19 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:19 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:21 volumio ntpd[1334]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 06 21:59:21 volumio ntpd[1334]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 06 21:59:21 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.1 Dec 06 21:59:21 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.123 Dec 06 21:59:21 volumio ntpd[1334]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 06 21:59:21 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:21 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:21 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:21 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:22 volumio ntpd[1334]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 06 21:59:22 volumio ntpd[1334]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 06 21:59:22 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.123 Dec 06 21:59:22 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.1 Dec 06 21:59:22 volumio ntpd[1334]: DNS: Pool skipping: 2606:4700:f1::1 Dec 06 21:59:22 volumio ntpd[1334]: DNS: Pool skipping: 2606:4700:f1::123 Dec 06 21:59:22 volumio ntpd[1334]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 06 21:59:23 volumio ntpd[1334]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 06 21:59:23 volumio ntpd[1334]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 06 21:59:23 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.1 Dec 06 21:59:23 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.123 Dec 06 21:59:23 volumio ntpd[1334]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 06 21:59:23 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:23 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:23 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:23 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:24 volumio ntpd[1334]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 06 21:59:24 volumio ntpd[1334]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 06 21:59:24 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.123 Dec 06 21:59:24 volumio ntpd[1334]: DNS: Pool skipping: 162.159.200.1 Dec 06 21:59:24 volumio ntpd[1334]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 06 21:59:24 volumio volumio[1614]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 06 21:59:25 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:25 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:25 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:25 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:27 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:27 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:27 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:27 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:29 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:29 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:29 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:29 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:30 volumio volumio[1614]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Dec 06 21:59:31 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:31 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:31 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:31 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:33 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:33 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:33 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:33 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:35 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:35 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:35 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:35 volumio volumio[1614]: error: FusionDsp - Monitor WebSocket error: [object Object] Dec 06 21:59:36 volumio volumio[1614]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Dec 06 21:59:36 volumio volumio[1614]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Dec 06 21:59:38 volumio volumio[1614]: --2025-12-06 21:59:36-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Dec 06 21:59:38 volumio volumio[1614]: Resolving github.com (github.com)... 20.207.73.82 Dec 06 21:59:38 volumio volumio[1614]: Connecting to github.com (github.com)|20.207.73.82|:443... connected. Dec 06 21:59:38 volumio volumio[1614]: HTTP request sent, awaiting response... 404 Not Found Dec 06 21:59:38 volumio volumio[1614]: 2025-12-06 21:59:37 ERROR 404: Not Found. Dec 06 21:59:38 volumio volumio[1614]: --2025-12-06 21:59:37-- http://a/ Dec 06 21:59:38 volumio volumio[1614]: Resolving a (a)... failed: Device or resource busy. Dec 06 21:59:38 volumio volumio[1614]: wget: unable to resolve host address ‘a’ Dec 06 21:59:38 volumio volumio[1614]: --2025-12-06 21:59:37-- http://packet.zip/ Dec 06 21:59:38 volumio volumio[1614]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 06 21:59:38 volumio volumio[1614]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 06 21:59:38 volumio volumio[1614]: HTTP request sent, awaiting response... 302 Found Dec 06 21:59:38 volumio volumio[1614]: Location: http://www.packet.zip/ [following] Dec 06 21:59:38 volumio volumio[1614]: --2025-12-06 21:59:38-- http://www.packet.zip/ Dec 06 21:59:38 volumio volumio[1614]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 06 21:59:38 volumio volumio[1614]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 06 21:59:38 volumio volumio[1614]: HTTP request sent, awaiting response... 403 Forbidden Dec 06 21:59:38 volumio volumio[1614]: 2025-12-06 21:59:38 ERROR 403: Forbidden. Dec 06 21:59:38 volumio volumio[1614]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove 'a': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 21:59:38 volumio volumio[1614]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove 'a': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 06 21:59:38 volumio volumio[1614]: at checkExecSyncError (node:child_process:887:11) Dec 06 21:59:38 volumio volumio[1614]: at execSync (node:child_process:959:15) Dec 06 21:59:38 volumio volumio[1614]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Dec 06 21:59:38 volumio volumio[1614]: at new Promise () Dec 06 21:59:38 volumio volumio[1614]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Dec 06 21:59:38 volumio volumio[1614]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 06 21:59:38 volumio volumio[1614]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 06 21:59:38 volumio volumio[1614]: at Socket.emit (node:events:514:28) Dec 06 21:59:38 volumio volumio[1614]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 06 21:59:38 volumio volumio[1614]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 06 21:59:38 volumio volumio[1614]: status: 1, Dec 06 21:59:38 volumio volumio[1614]: signal: null, Dec 06 21:59:38 volumio volumio[1614]: output: [ Dec 06 21:59:38 volumio volumio[1614]: null, Dec 06 21:59:38 volumio volumio[1614]: , Dec 06 21:59:38 volumio volumio[1614]: Dec 06 21:59:38 volumio volumio[1614]: ], Dec 06 21:59:38 volumio volumio[1614]: pid: 5459, Dec 06 21:59:38 volumio volumio[1614]: stdout: , Dec 06 21:59:38 volumio volumio[1614]: stderr: Dec 06 21:59:38 volumio volumio[1614]: } Dec 06 21:59:38 volumio volumio[1614]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 06 21:59:38 volumio sudo[5475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-06 21:58' Dec 06 21:59:38 volumio sudo[5475]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"