Dec 22 13:36:00 volumio volumio[25242]: info: Get:14 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libnsl-dev armhf 1.3.0-2 [58.9 kB] Dec 22 13:36:00 volumio volumio[25242]: info: Get:15 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf rpcsvc-proto armhf 1.4.3-1 [55.1 kB] Dec 22 13:36:01 volumio volumio[25242]: info: Get:16 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libexpat1-dev armhf 2.5.0-1+deb12u2 [132 kB] Dec 22 13:36:01 volumio volumio[25242]: info: Get:17 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libjpeg62-turbo-dev armhf 1:2.1.5-2 [258 kB] Dec 22 13:36:01 volumio volumio[25242]: info: Get:18 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libjpeg-dev armhf 1:2.1.5-2 [71.8 kB] Dec 22 13:36:02 volumio volumio[25242]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libpython3.11-dev armhf 3.11.2-6+deb12u6 [3372 kB] Dec 22 13:36:02 volumio volumio[25242]: info: Get:20 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf libpython3-dev armhf 3.11.2-1 [9248 B] Dec 22 13:36:02 volumio volumio[25242]: info: Get:21 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf manpages-dev all 6.03-2 [2030 kB] Dec 22 13:36:02 volumio volumio[25242]: info: Get:22 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3.11-dev armhf 3.11.2-6+deb12u6 [618 kB] Dec 22 13:36:03 volumio volumio[25242]: info: Get:23 http://ftp.icm.edu.pl/pub/Linux/distributions/raspbian/raspbian bookworm/main armhf python3-dev armhf 3.11.2-1 [26.2 kB] Dec 22 13:36:04 volumio volumio[25242]: info: Fetched 14.3 MB in 6s (2329 kB/s) Dec 22 13:36:04 volumio volumio[25242]: info: Selecting previously unselected package manpages. Dec 22 13:36:04 volumio volumio[25242]: [620B blob data] Dec 22 13:36:04 volumio volumio[25242]: info: Preparing to unpack .../00-manpages_6.03-2_all.deb ... Dec 22 13:36:04 volumio volumio[25242]: info: Unpacking manpages (6.03-2) ... Dec 22 13:36:07 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:07 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:07 volumio volumio[25242]: info: Selecting previously unselected package libabsl20220623:armhf. Dec 22 13:36:07 volumio volumio[25242]: info: Preparing to unpack .../01-libabsl20220623_20220623.1-1+rpi1+deb12u2_armhf.deb ... Dec 22 13:36:07 volumio volumio[25242]: info: Unpacking libabsl20220623:armhf (20220623.1-1+rpi1+deb12u2) ... Dec 22 13:36:08 volumio volumio[25242]: info: Selecting previously unselected package libgav1-1:armhf. Dec 22 13:36:08 volumio volumio[25242]: info: Preparing to unpack .../02-libgav1-1_0.18.0-1+rpi1+b1_armhf.deb ... Dec 22 13:36:08 volumio volumio[25242]: info: Unpacking libgav1-1:armhf (0.18.0-1+rpi1+b1) ... Dec 22 13:36:08 volumio volumio[25242]: info: Selecting previously unselected package libyuv0:armhf. Dec 22 13:36:08 volumio volumio[25242]: info: Preparing to unpack .../03-libyuv0_0.0~git20230123.b2528b0-1_armhf.deb ... Dec 22 13:36:08 volumio volumio[25242]: info: Unpacking libyuv0:armhf (0.0~git20230123.b2528b0-1) ... Dec 22 13:36:08 volumio volumio[25242]: info: Selecting previously unselected package libavif15:armhf. Dec 22 13:36:08 volumio volumio[25242]: info: Preparing to unpack .../04-libavif15_0.11.1-1+deb12u1_armhf.deb ... Dec 22 13:36:08 volumio volumio[25242]: info: Unpacking libavif15:armhf (0.11.1-1+deb12u1) ... Dec 22 13:36:08 volumio volumio[25242]: info: Selecting previously unselected package libc-dev-bin. Dec 22 13:36:08 volumio volumio[25242]: info: Preparing to unpack .../05-libc-dev-bin_2.36-9+rpt2+deb12u13_armhf.deb ... Dec 22 13:36:08 volumio volumio[25242]: info: Unpacking libc-dev-bin (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:09 volumio volumio[25242]: info: Selecting previously unselected package libgd3:armhf. Dec 22 13:36:09 volumio volumio[25242]: info: Preparing to unpack .../06-libgd3_2.3.3-9_armhf.deb ... Dec 22 13:36:09 volumio volumio[25242]: info: Unpacking libgd3:armhf (2.3.3-9) ... Dec 22 13:36:09 volumio volumio[25242]: info: Selecting previously unselected package libc-devtools. Dec 22 13:36:09 volumio volumio[25242]: info: Preparing to unpack .../07-libc-devtools_2.36-9+rpt2+deb12u13_armhf.deb ... Dec 22 13:36:09 volumio volumio[25242]: info: Unpacking libc-devtools (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:10 volumio volumio[25242]: info: Selecting previously unselected package linux-libc-dev. Dec 22 13:36:10 volumio volumio[25242]: info: Preparing to unpack .../08-linux-libc-dev_1%3a6.12.47-1+rpt1~bookworm_all.deb ... Dec 22 13:36:10 volumio volumio[25242]: info: Unpacking linux-libc-dev (1:6.12.47-1+rpt1~bookworm) ... Dec 22 13:36:17 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:17 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:17 volumio volumio[25242]: info: Listing playlists Dec 22 13:36:17 volumio volumio[25242]: info: Listing playlists Dec 22 13:36:17 volumio volumio[25242]: info: Selecting previously unselected package libcrypt-dev:armhf. Dec 22 13:36:17 volumio volumio[25242]: info: Preparing to unpack .../09-libcrypt-dev_1%3a4.4.33-2_armhf.deb ... Dec 22 13:36:17 volumio volumio[25242]: info: Unpacking libcrypt-dev:armhf (1:4.4.33-2) ... Dec 22 13:36:17 volumio volumio[25242]: info: Selecting previously unselected package libtirpc-dev:armhf. Dec 22 13:36:17 volumio volumio[25242]: info: Preparing to unpack .../10-libtirpc-dev_1.3.3+ds-1_armhf.deb ... Dec 22 13:36:17 volumio volumio[25242]: info: Unpacking libtirpc-dev:armhf (1.3.3+ds-1) ... Dec 22 13:36:19 volumio volumio[25242]: info: Selecting previously unselected package libnsl-dev:armhf. Dec 22 13:36:19 volumio volumio[25242]: info: Preparing to unpack .../11-libnsl-dev_1.3.0-2_armhf.deb ... Dec 22 13:36:19 volumio volumio[25242]: info: Unpacking libnsl-dev:armhf (1.3.0-2) ... Dec 22 13:36:20 volumio volumio[25242]: info: Selecting previously unselected package rpcsvc-proto. Dec 22 13:36:20 volumio volumio[25242]: info: Preparing to unpack .../12-rpcsvc-proto_1.4.3-1_armhf.deb ... Dec 22 13:36:20 volumio volumio[25242]: info: Unpacking rpcsvc-proto (1.4.3-1) ... Dec 22 13:36:20 volumio volumio[25242]: info: Selecting previously unselected package libc6-dev:armhf. Dec 22 13:36:20 volumio volumio[25242]: info: Preparing to unpack .../13-libc6-dev_2.36-9+rpt2+deb12u13_armhf.deb ... Dec 22 13:36:21 volumio volumio[25242]: info: Unpacking libc6-dev:armhf (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:24 volumio volumio[25242]: info: Selecting previously unselected package libexpat1-dev:armhf. Dec 22 13:36:24 volumio volumio[25242]: info: Preparing to unpack .../14-libexpat1-dev_2.5.0-1+deb12u2_armhf.deb ... Dec 22 13:36:24 volumio volumio[25242]: info: Unpacking libexpat1-dev:armhf (2.5.0-1+deb12u2) ... Dec 22 13:36:24 volumio volumio[25242]: info: Selecting previously unselected package libjpeg62-turbo-dev:armhf. Dec 22 13:36:24 volumio volumio[25242]: info: Preparing to unpack .../15-libjpeg62-turbo-dev_1%3a2.1.5-2_armhf.deb ... Dec 22 13:36:24 volumio volumio[25242]: info: Unpacking libjpeg62-turbo-dev:armhf (1:2.1.5-2) ... Dec 22 13:36:24 volumio volumio[25242]: info: Selecting previously unselected package libjpeg-dev:armhf. Dec 22 13:36:24 volumio volumio[25242]: info: Preparing to unpack .../16-libjpeg-dev_1%3a2.1.5-2_armhf.deb ... Dec 22 13:36:24 volumio volumio[25242]: info: Unpacking libjpeg-dev:armhf (1:2.1.5-2) ... Dec 22 13:36:25 volumio volumio[25242]: info: Selecting previously unselected package zlib1g-dev:armhf. Dec 22 13:36:25 volumio volumio[25242]: info: Preparing to unpack .../17-zlib1g-dev_1%3a1.2.13.dfsg-1+rpt1_armhf.deb ... Dec 22 13:36:25 volumio volumio[25242]: info: Unpacking zlib1g-dev:armhf (1:1.2.13.dfsg-1+rpt1) ... Dec 22 13:36:25 volumio volumio[25242]: info: Selecting previously unselected package libpython3.11-dev:armhf. Dec 22 13:36:25 volumio volumio[25242]: info: Preparing to unpack .../18-libpython3.11-dev_3.11.2-6+deb12u6_armhf.deb ... Dec 22 13:36:25 volumio volumio[25242]: info: Unpacking libpython3.11-dev:armhf (3.11.2-6+deb12u6) ... Dec 22 13:36:27 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:27 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:29 volumio volumio[25242]: info: Selecting previously unselected package libpython3-dev:armhf. Dec 22 13:36:29 volumio volumio[25242]: info: Preparing to unpack .../19-libpython3-dev_3.11.2-1_armhf.deb ... Dec 22 13:36:29 volumio volumio[25242]: info: Unpacking libpython3-dev:armhf (3.11.2-1) ... Dec 22 13:36:29 volumio volumio[25242]: info: Selecting previously unselected package manpages-dev. Dec 22 13:36:29 volumio volumio[25242]: info: Preparing to unpack .../20-manpages-dev_6.03-2_all.deb ... Dec 22 13:36:29 volumio volumio[25242]: info: Unpacking manpages-dev (6.03-2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Selecting previously unselected package python3.11-dev. Dec 22 13:36:36 volumio volumio[25242]: info: Preparing to unpack .../21-python3.11-dev_3.11.2-6+deb12u6_armhf.deb ... Dec 22 13:36:36 volumio volumio[25242]: info: Unpacking python3.11-dev (3.11.2-6+deb12u6) ... Dec 22 13:36:36 volumio volumio[25242]: info: Selecting previously unselected package python3-dev. Dec 22 13:36:36 volumio volumio[25242]: info: Preparing to unpack .../22-python3-dev_3.11.2-1_armhf.deb ... Dec 22 13:36:36 volumio volumio[25242]: info: Unpacking python3-dev (3.11.2-1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libabsl20220623:armhf (20220623.1-1+rpi1+deb12u2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up manpages (6.03-2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up linux-libc-dev (1:6.12.47-1+rpt1~bookworm) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libtirpc-dev:armhf (1.3.3+ds-1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up rpcsvc-proto (1.4.3-1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libgav1-1:armhf (0.18.0-1+rpi1+b1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libnsl-dev:armhf (1.3.0-2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libcrypt-dev:armhf (1:4.4.33-2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libc-dev-bin (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libyuv0:armhf (0.0~git20230123.b2528b0-1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up manpages-dev (6.03-2) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libavif15:armhf (0.11.1-1+deb12u1) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libgd3:armhf (2.3.3-9) ... Dec 22 13:36:36 volumio volumio[25242]: info: Setting up libc6-dev:armhf (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libc-devtools (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libexpat1-dev:armhf (2.5.0-1+deb12u2) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libjpeg62-turbo-dev:armhf (1:2.1.5-2) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up zlib1g-dev:armhf (1:1.2.13.dfsg-1+rpt1) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libjpeg-dev:armhf (1:2.1.5-2) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libpython3.11-dev:armhf (3.11.2-6+deb12u6) ... Dec 22 13:36:37 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:37 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:37 volumio volumio[25242]: info: Listing playlists Dec 22 13:36:37 volumio volumio[25242]: info: Listing playlists Dec 22 13:36:37 volumio volumio[25242]: info: Setting up libpython3-dev:armhf (3.11.2-1) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up python3.11-dev (3.11.2-6+deb12u6) ... Dec 22 13:36:37 volumio volumio[25242]: info: Setting up python3-dev (3.11.2-1) ... Dec 22 13:36:37 volumio volumio[25242]: info: Processing triggers for man-db (2.11.2-2) ... Dec 22 13:36:38 volumio volumio[25242]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Dec 22 13:36:39 volumio sudo[12771]: pam_unix(sudo:session): session closed for user root Dec 22 13:36:39 volumio sudo[13271]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 22 13:36:39 volumio sudo[13271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 13:36:39 volumio systemd[1]: Reloading. Dec 22 13:36:40 volumio sudo[13271]: pam_unix(sudo:session): session closed for user root Dec 22 13:36:40 volumio volumio[25242]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so Dec 22 13:36:40 volumio volumio[25242]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0 Dec 22 13:36:45 volumio sudo[13321]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Dec 22 13:36:45 volumio sudo[13321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 13:36:45 volumio sudo[13321]: pam_unix(sudo:session): session closed for user root Dec 22 13:36:45 volumio sudo[12507]: pam_unix(sudo:session): session closed for user root Dec 22 13:36:45 volumio volumio[25242]: Plugin install end detected on script Dec 22 13:36:45 volumio volumio[25242]: info: Install script completed Dec 22 13:36:45 volumio volumio[25242]: info: Adding reference to registry Dec 22 13:36:45 volumio volumio[25242]: info: Done installing plugin. Dec 22 13:36:45 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 13:36:45 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 13:36:45 volumio volumio[25242]: info: Folder /tmp/plugins removed Dec 22 13:36:45 volumio volumio[25242]: info: Folder /tmp/downloaded_plugin.zip removed Dec 22 13:36:45 volumio volumio[25242]: info: Folder /data/temp removed Dec 22 13:36:47 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:47 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:57 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:36:57 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:36:57 volumio volumio[25242]: info: Listing playlists Dec 22 13:36:57 volumio volumio[25242]: info: Listing playlists Dec 22 13:37:03 volumio volumio[25242]: info: Enabling plugin peppymeterbasic Dec 22 13:37:03 volumio volumio[25242]: info: Loading plugin "peppymeterbasic"... Dec 22 13:37:04 volumio volumio[25242]: info: Preparing to generate the ALSA configuration file Dec 22 13:37:04 volumio volumio[25242]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:04 volumio volumio[25242]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:04 volumio volumio[25242]: info: Reading ALSA contributions from plugins. Dec 22 13:37:04 volumio volumio[25242]: info: Asound.conf file written Dec 22 13:37:04 volumio sudo[13406]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 13:37:04 volumio sudo[13406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio sudo[13406]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 13:37:04 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 13:37:04 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 22 13:37:04 volumio volumio[25242]: info: Output device has changed, restarting MPD Dec 22 13:37:04 volumio volumio[25242]: info: Output device has changed, restarting Shairport Sync Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:04 volumio sudo[13412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 13:37:04 volumio sudo[13412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio sudo[13412]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio sudo[13415]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 13:37:04 volumio sudo[13415]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio volumio[25242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:37:04 volumio volumio[25242]: info: PLUGIN START: peppymeterbasic Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 13:37:04 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 13:37:04 volumio volumio[25242]: info: Preparing to generate the ALSA configuration file Dec 22 13:37:04 volumio sudo[13422]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 13:37:04 volumio volumio[25242]: info: Done. Dec 22 13:37:04 volumio sudo[13422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio volumio[25242]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:04 volumio volumio[25242]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:04 volumio volumio[25242]: info: Reading ALSA contributions from plugins. Dec 22 13:37:04 volumio volumio[25242]: info: MPD Permissions set Dec 22 13:37:04 volumio sudo[13422]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio volumio[25242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: snd_dummy loaded Dec 22 13:37:04 volumio volumio[25242]: info: Starting Shairport Sync Dec 22 13:37:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 13:37:04 volumio systemd[1]: mpd.service: Consumed 1.884s CPU time. Dec 22 13:37:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 13:37:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 13:37:04 volumio volumio[25242]: info: Asound.conf file unchanged, so no further update is needed Dec 22 13:37:04 volumio volumio[25242]: info: Output device has changed, restarting MPD Dec 22 13:37:04 volumio sudo[13431]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:37:04 volumio sudo[13431]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio volumio[25242]: info: Output device has changed, restarting Shairport Sync Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:04 volumio sudo[13434]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 13:37:04 volumio sudo[13434]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio sudo[13434]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio sudo[13436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 13:37:04 volumio sudo[13436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio volumio[25242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:37:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 13:37:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 13:37:04 volumio volumio[25242]: info: MPD Permissions set Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:37:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:04 volumio systemd[1]: shairport-sync.service: Consumed 1.943s CPU time. Dec 22 13:37:04 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 13:37:04 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 13:37:04 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: Starting Shairport Sync Dec 22 13:37:04 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 13:37:04 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 13:37:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:04 volumio sudo[13431]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio sudo[13455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:37:04 volumio volumio[25242]: info: Shairport-Sync Started Dec 22 13:37:04 volumio sudo[13455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:04 volumio volumio[25242]: (node:25242) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 13:37:04 volumio volumio[25242]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 22 13:37:04 volumio volumio[25242]: (node:25242) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 13:37:04 volumio volumio[25242]: (node:25242) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 13:37:04 volumio volumio[25242]: (node:25242) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 13:37:04 volumio volumio[25242]: (node:25242) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 22 13:37:04 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:37:04 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:37:04 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:04 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:04 volumio sudo[13455]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio volumio[25242]: info: Shairport-Sync Started Dec 22 13:37:04 volumio sudo[13453]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 13:37:04 volumio sudo[13453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 13:37:04 volumio sudo[13453]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:04 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:05 volumio mpd[13474]: 2025-12-22T13:37:05 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 13:37:06 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 13:37:06 volumio sudo[13436]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:06 volumio sudo[13415]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:06 volumio volumio[25242]: error: MPD error: The expression evaluated to a falsy value: Dec 22 13:37:06 volumio volumio[25242]: assert.ok(self.idling) Dec 22 13:37:06 volumio volumio[25242]: error: The expression evaluated to a falsy value: Dec 22 13:37:06 volumio volumio[25242]: assert.ok(self.idling) Dec 22 13:37:06 volumio volumio[25242]: error: updateQueue error: null Dec 22 13:37:07 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:37:07 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:37:09 volumio sudo[13509]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 13:37:09 volumio sudo[13509]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:09 volumio sudo[13511]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 13:37:09 volumio sudo[13511]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:09 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 13:37:09 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 13:37:09 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:09 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:09 volumio sudo[13509]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:09 volumio sudo[13511]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:09 volumio mpd_monitor.sh[13514]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 13:37:09 volumio volumio[25242]: info: Successfully started MPD Monitor Dec 22 13:37:09 volumio volumio[25242]: info: Successfully started MPD Monitor Dec 22 13:37:10 volumio volumio[25242]: info: Enabling plugin peppymeterbasic Dec 22 13:37:10 volumio volumio[25242]: info: Loading plugin "peppymeterbasic"... Dec 22 13:37:10 volumio volumio[25242]: info: Preparing to generate the ALSA configuration file Dec 22 13:37:10 volumio volumio[25242]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:10 volumio volumio[25242]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:10 volumio volumio[25242]: info: Reading ALSA contributions from plugins. Dec 22 13:37:10 volumio volumio[25242]: info: Asound.conf file unchanged, so no further update is needed Dec 22 13:37:10 volumio volumio[25242]: info: Output device has changed, restarting MPD Dec 22 13:37:10 volumio volumio[25242]: info: Output device has changed, restarting Shairport Sync Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:10 volumio sudo[13521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 13:37:10 volumio sudo[13521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio sudo[13521]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio sudo[13523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 13:37:10 volumio sudo[13523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio volumio[25242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:37:10 volumio volumio[25242]: info: PLUGIN START: peppymeterbasic Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 13:37:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 13:37:10 volumio volumio[25242]: info: Preparing to generate the ALSA configuration file Dec 22 13:37:10 volumio sudo[13531]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 22 13:37:10 volumio sudo[13531]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio sudo[13531]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio volumio[25242]: info: Done. Dec 22 13:37:10 volumio volumio[25242]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:10 volumio volumio[25242]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:10 volumio volumio[25242]: info: Reading ALSA contributions from plugins. Dec 22 13:37:10 volumio volumio[25242]: info: snd_dummy loaded Dec 22 13:37:10 volumio volumio[25242]: info: MPD Permissions set Dec 22 13:37:10 volumio volumio[25242]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: Starting Shairport Sync Dec 22 13:37:10 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 13:37:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 13:37:10 volumio systemd[1]: mpd.service: Consumed 1.884s CPU time. Dec 22 13:37:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 13:37:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 13:37:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 13:37:10 volumio volumio[25242]: info: Asound.conf file unchanged, so no further update is needed Dec 22 13:37:10 volumio volumio[25242]: info: Output device has changed, restarting MPD Dec 22 13:37:10 volumio sudo[13539]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:37:10 volumio sudo[13539]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio volumio[25242]: info: Output device has changed, restarting Shairport Sync Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:10 volumio sudo[13542]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 13:37:10 volumio sudo[13542]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio sudo[13542]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio volumio[25242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:37:10 volumio sudo[13547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 13:37:10 volumio sudo[13547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 13:37:10 volumio volumio[25242]: info: MPD Permissions set Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: Starting Shairport Sync Dec 22 13:37:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:37:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:37:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:10 volumio systemd[1]: shairport-sync.service: Consumed 1.581s CPU time. Dec 22 13:37:10 volumio sudo[13562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:37:10 volumio sudo[13562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:10 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 13:37:10 volumio sudo[13539]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 13:37:10 volumio volumio[25242]: info: Shairport-Sync Started Dec 22 13:37:10 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 13:37:10 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 13:37:10 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 13:37:10 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 13:37:10 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 13:37:10 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:37:10 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:37:10 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:10 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:10 volumio sudo[13562]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio volumio[25242]: info: Shairport-Sync Started Dec 22 13:37:10 volumio sudo[13566]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 13:37:10 volumio sudo[13566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 13:37:10 volumio sudo[13566]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:10 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:11 volumio mpd[13583]: 2025-12-22T13:37:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 13:37:12 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 13:37:12 volumio sudo[13547]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:12 volumio sudo[13523]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:12 volumio volumio[25242]: error: MPD error: The expression evaluated to a falsy value: Dec 22 13:37:12 volumio volumio[25242]: assert.ok(self.idling) Dec 22 13:37:12 volumio volumio[25242]: error: The expression evaluated to a falsy value: Dec 22 13:37:12 volumio volumio[25242]: assert.ok(self.idling) Dec 22 13:37:12 volumio volumio[25242]: error: updateQueue error: null Dec 22 13:37:15 volumio sudo[13608]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 13:37:15 volumio sudo[13608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:15 volumio sudo[13610]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 13:37:15 volumio sudo[13610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:15 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 13:37:15 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 13:37:15 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:15 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:15 volumio sudo[13608]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:15 volumio sudo[13610]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:15 volumio mpd_monitor.sh[13613]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 13:37:15 volumio volumio[25242]: info: Successfully started MPD Monitor Dec 22 13:37:15 volumio volumio[25242]: info: Successfully started MPD Monitor Dec 22 13:37:17 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:37:17 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:37:17 volumio volumio[25242]: info: Listing playlists Dec 22 13:37:17 volumio volumio[25242]: info: Listing playlists Dec 22 13:37:26 volumio volumio[25242]: info: Stopping peppyspectrum service Dec 22 13:37:26 volumio volumio[25242]: info: CoreStateMachine::stop Dec 22 13:37:26 volumio volumio[25242]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 13:37:26 volumio volumio[25242]: info: Disabling plugin peppyspectrum Dec 22 13:37:26 volumio volumio[25242]: info: Preparing to generate the ALSA configuration file Dec 22 13:37:26 volumio volumio[25242]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 22 13:37:26 volumio volumio[25242]: info: Reading ALSA contributions from plugins. Dec 22 13:37:26 volumio volumio[25242]: info: Asound.conf file written Dec 22 13:37:26 volumio sudo[13667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Dec 22 13:37:26 volumio sudo[13667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:26 volumio sudo[13671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 13:37:26 volumio sudo[13671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:26 volumio sudo[13671]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:26 volumio sudo[13667]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:26 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 13:37:26 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 13:37:26 volumio volumio[25242]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Dec 22 13:37:26 volumio volumio[25242]: info: Output device has changed, restarting MPD Dec 22 13:37:26 volumio volumio[25242]: info: Output device has changed, restarting Shairport Sync Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:26 volumio sudo[13677]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 13:37:26 volumio sudo[13677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:26 volumio sudo[13677]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:26 volumio sudo[13679]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 13:37:26 volumio sudo[13679]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:26 volumio volumio[25242]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 13:37:26 volumio volumio[25242]: info: Done. Dec 22 13:37:26 volumio volumio[25242]: info: MPD Permissions set Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 13:37:26 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:26 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 13:37:26 volumio volumio[25242]: info: Starting Shairport Sync Dec 22 13:37:26 volumio sudo[13692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 13:37:26 volumio sudo[13692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:26 volumio volumio[25242]: error: Upnp client error: Error: read ECONNRESET Dec 22 13:37:26 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 13:37:26 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 13:37:26 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:26 volumio systemd[1]: shairport-sync.service: Consumed 1.610s CPU time. Dec 22 13:37:26 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 13:37:26 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 22 13:37:26 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 13:37:26 volumio systemd[1]: mpd.service: Consumed 1.868s CPU time. Dec 22 13:37:26 volumio sudo[13692]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:26 volumio volumio[25242]: info: Shairport-Sync Started Dec 22 13:37:26 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 22 13:37:26 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 13:37:26 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 13:37:26 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 13:37:27 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 13:37:27 volumio sudo[13696]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 13:37:27 volumio sudo[13696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 13:37:27 volumio sudo[13696]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:27 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:37:27 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:37:27 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:27 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 13:37:28 volumio mpd[13714]: 2025-12-22T13:37:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 13:37:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 13:37:28 volumio sudo[13679]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:28 volumio volumio[25242]: error: updateQueue error: null Dec 22 13:37:30 volumio volumio[25242]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:37:31 volumio sudo[13735]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 13:37:31 volumio sudo[13735]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 13:37:31 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 13:37:31 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 13:37:31 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:31 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 13:37:31 volumio sudo[13735]: pam_unix(sudo:session): session closed for user root Dec 22 13:37:31 volumio volumio[25242]: info: Successfully started MPD Monitor Dec 22 13:37:31 volumio mpd_monitor.sh[13737]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 13:37:36 volumio volumio[25242]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Dec 22 13:37:36 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Dec 22 13:37:36 volumio volumio[25242]: aaaaaaaaaaa 800,480 Dec 22 13:37:36 volumio volumio[25242]: info: PeppyMeterBasic ---800480 Dec 22 13:37:36 volumio volumio[25242]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 22 13:37:36 volumio volumio[25242]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:37:36 volumio volumio[25242]: info: CoreCommandRouter::Close All Modals sent Dec 22 13:37:37 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:37:37 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:37:37 volumio volumio[25242]: info: Listing playlists Dec 22 13:37:37 volumio volumio[25242]: info: Listing playlists Dec 22 13:37:38 volumio volumio[25242]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Dec 22 13:37:38 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Dec 22 13:37:38 volumio volumio[25242]: aaaaaaaaaaa 800,480 Dec 22 13:37:38 volumio volumio[25242]: info: PeppyMeterBasic ---800480 Dec 22 13:37:38 volumio volumio[25242]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 22 13:37:38 volumio volumio[25242]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:37:38 volumio volumio[25242]: info: CoreCommandRouter::Close All Modals sent Dec 22 13:37:47 volumio volumio[25242]: info: CoreCommandRouter::volumioGetState Dec 22 13:37:47 volumio volumio[25242]: info: CorePlayQueue::getTrack 1 Dec 22 13:37:48 volumio volumio[25242]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 22 13:37:48 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 22 13:37:48 volumio volumio[25242]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 22 13:37:49 volumio volumio[25242]: --2025-12-22 13:37:48-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 22 13:37:49 volumio volumio[25242]: Resolving github.com (github.com)... 140.82.121.4 Dec 22 13:37:49 volumio volumio[25242]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 22 13:37:49 volumio volumio[25242]: HTTP request sent, awaiting response... 302 Found Dec 22 13:37:49 volumio volumio[25242]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 22 13:37:49 volumio volumio[25242]: --2025-12-22 13:37:48-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 22 13:37:49 volumio volumio[25242]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.110.133, 185.199.109.133, ... Dec 22 13:37:49 volumio volumio[25242]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Dec 22 13:37:49 volumio volumio[25242]: HTTP request sent, awaiting response... 200 OK Dec 22 13:37:49 volumio volumio[25242]: Length: 362 [text/plain] Dec 22 13:37:49 volumio volumio[25242]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 22 13:37:49 volumio volumio[25242]: 0K 100% 6.36M=0s Dec 22 13:37:49 volumio volumio[25242]: 2025-12-22 13:37:49 (6.36 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 22 13:37:49 volumio volumio[25242]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 22 13:37:49 volumio volumio[25242]: info: CoreCommandRouter::Close All Modals sent Dec 22 13:37:54 volumio volumio[25242]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 22 13:37:54 volumio volumio[25242]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 22 13:37:56 volumio volumio[25242]: --2025-12-22 13:37:54-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 22 13:37:56 volumio volumio[25242]: Resolving github.com (github.com)... 140.82.121.4 Dec 22 13:37:56 volumio volumio[25242]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 22 13:37:56 volumio volumio[25242]: HTTP request sent, awaiting response... 404 Not Found Dec 22 13:37:56 volumio volumio[25242]: 2025-12-22 13:37:55 ERROR 404: Not Found. Dec 22 13:37:56 volumio volumio[25242]: --2025-12-22 13:37:55-- http://a/ Dec 22 13:37:56 volumio volumio[25242]: Resolving a (a)... failed: Device or resource busy. Dec 22 13:37:56 volumio volumio[25242]: wget: unable to resolve host address ‘a’ Dec 22 13:37:56 volumio volumio[25242]: --2025-12-22 13:37:55-- http://packet.zip/ Dec 22 13:37:56 volumio volumio[25242]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 22 13:37:56 volumio volumio[25242]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 22 13:37:56 volumio volumio[25242]: HTTP request sent, awaiting response... 302 Found Dec 22 13:37:56 volumio volumio[25242]: Location: http://www.packet.zip/ [following] Dec 22 13:37:56 volumio volumio[25242]: --2025-12-22 13:37:55-- http://www.packet.zip/ Dec 22 13:37:56 volumio volumio[25242]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 22 13:37:56 volumio volumio[25242]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 22 13:37:56 volumio volumio[25242]: HTTP request sent, awaiting response... 403 Forbidden Dec 22 13:37:56 volumio volumio[25242]: 2025-12-22 13:37:56 ERROR 403: Forbidden. Dec 22 13:37:56 volumio volumio[25242]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove 'a': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:37:56 volumio volumio[25242]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove 'a': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 22 13:37:56 volumio volumio[25242]: at checkExecSyncError (node:child_process:887:11) Dec 22 13:37:56 volumio volumio[25242]: at execSync (node:child_process:959:15) Dec 22 13:37:56 volumio volumio[25242]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Dec 22 13:37:56 volumio volumio[25242]: at new Promise () Dec 22 13:37:56 volumio volumio[25242]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Dec 22 13:37:56 volumio volumio[25242]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 22 13:37:56 volumio volumio[25242]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 22 13:37:56 volumio volumio[25242]: at Socket.emit (node:events:514:28) Dec 22 13:37:56 volumio volumio[25242]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 22 13:37:56 volumio volumio[25242]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 22 13:37:56 volumio volumio[25242]: status: 1, Dec 22 13:37:56 volumio volumio[25242]: signal: null, Dec 22 13:37:56 volumio volumio[25242]: output: [ Dec 22 13:37:56 volumio volumio[25242]: null, Dec 22 13:37:56 volumio volumio[25242]: , Dec 22 13:37:56 volumio volumio[25242]: Dec 22 13:37:56 volumio volumio[25242]: ], Dec 22 13:37:56 volumio volumio[25242]: pid: 13864, Dec 22 13:37:56 volumio volumio[25242]: stdout: , Dec 22 13:37:56 volumio volumio[25242]: stderr: Dec 22 13:37:56 volumio volumio[25242]: } Dec 22 13:37:56 volumio volumio[25242]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 13:37:56 volumio sudo[13883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 13:36' Dec 22 13:37:56 volumio sudo[13883]: 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"