May 31 21:10:13 solhi-fi volumio[13971]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf May 31 21:10:13 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:13.393-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio[13971]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf May 31 21:10:14 solhi-fi volumio[13971]: info: Folder /tmp/plugins removed May 31 21:10:14 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:14.757-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:14.822-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio[13971]: info: Check plugin dependencies May 31 21:10:14 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 21:10:14 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:14.839-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio[13971]: info: Checking if plugin already exists May 31 21:10:14 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:14.842-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio[13971]: info: Rename folder May 31 21:10:14 solhi-fi volumio[13971]: info: Folder /tmp/downloaded_plugin.zip removed May 31 21:10:14 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:14.855-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:14 solhi-fi volumio[13971]: info: Move to category May 31 21:10:15 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:15.511-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:15 solhi-fi volumio[13971]: info: Checking if install.sh is present May 31 21:10:15 solhi-fi volumio[13971]: info: Executing install.sh May 31 21:10:15 solhi-fi sudo[14609]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh May 31 21:10:15 solhi-fi sudo[14609]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:15 solhi-fi volumio[13971]: info: Installing peppyalsa plugin dependencies May 31 21:10:15 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:15.535-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:15 solhi-fi sudo[14613]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/usermod -aG render volumio May 31 21:10:15 solhi-fi sudo[14613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:15 solhi-fi sudo[14613]: pam_unix(sudo:session): session closed for user root May 31 21:10:15 solhi-fi sudo[14618]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update May 31 21:10:15 solhi-fi sudo[14618]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:15 solhi-fi volumio[13971]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease May 31 21:10:15 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:15.952-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:15 solhi-fi volumio[13971]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease May 31 21:10:15 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:15.984-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:17 solhi-fi volumio[13971]: info: Reading package lists... May 31 21:10:17 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:17.335-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:17 solhi-fi sudo[14618]: pam_unix(sudo:session): session closed for user root May 31 21:10:17 solhi-fi sudo[14755]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates May 31 21:10:17 solhi-fi sudo[14755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:17 solhi-fi sudo[14755]: pam_unix(sudo:session): session closed for user root May 31 21:10:17 solhi-fi sudo[14757]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates May 31 21:10:17 solhi-fi sudo[14757]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:17 solhi-fi sudo[14757]: pam_unix(sudo:session): session closed for user root May 31 21:10:17 solhi-fi volumio[13971]: info: installing apt packages May 31 21:10:17 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:17.400-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:17 solhi-fi sudo[14759]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev May 31 21:10:17 solhi-fi sudo[14759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:17 solhi-fi volumio[13971]: info: Reading package lists... May 31 21:10:17 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:17.447-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:17 solhi-fi volumio[13971]: info: Building dependency tree... May 31 21:10:17 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:17.717-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:17 solhi-fi volumio[13971]: info: Reading state information... May 31 21:10:17 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:17.725-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio[13971]: info: The following additional packages will be installed: May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.227-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio[13971]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-double3 libimagequant0 May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.243-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio[13971]: info: libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 libsdl2-mixer-2.0-0 May 31 21:10:18 solhi-fi volumio[13971]: info: libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy python3-olefile May 31 21:10:18 solhi-fi volumio[13971]: info: Suggested packages: May 31 21:10:18 solhi-fi volumio[13971]: info: bzip2-doc libfftw3-doc gcc gfortran python3-dev python3-pytest May 31 21:10:18 solhi-fi volumio[13971]: info: python-pil-doc python-pygame-doc timidity May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.250-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.251-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.252-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.252-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.252-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio[13971]: info: The following NEW packages will be installed: May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.271-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio[13971]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-dev libfftw3-double3 May 31 21:10:18 solhi-fi volumio[13971]: info: libimagequant0 libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 May 31 21:10:18 solhi-fi volumio[13971]: info: libsdl2-mixer-2.0-0 libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy May 31 21:10:18 solhi-fi volumio[13971]: info: python3-olefile python3-pil python3-pygame May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.282-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.287-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.287-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:18 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:18.287-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio[13971]: info: 0 upgraded, 19 newly installed, 0 to remove and 45 not upgraded. May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.359-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio[13971]: info: Need to get 9480 kB of archives. May 31 21:10:19 solhi-fi volumio[13971]: info: After this operation, 45.6 MB of additional disk space will be used. May 31 21:10:19 solhi-fi volumio[13971]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf bzip2 armhf 1.0.8-5+b2 [47.4 kB] May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.365-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.366-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.367-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio[13971]: info: Get:2 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf file armhf 1:5.44-3 [41.5 kB] May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.564-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:19 solhi-fi volumio[13971]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mailcap all 3.70+nmu1 [32.0 kB] May 31 21:10:19 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:19.837-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:20 solhi-fi volumio[13971]: info: Get:4 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf mime-support all 3.66 [10.9 kB] May 31 21:10:20 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:20.237-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:20 solhi-fi volumio[13971]: info: Get:5 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf fonts-freefont-ttf all 20120503-10 [2552 kB] May 31 21:10:20 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:20.617-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:21 solhi-fi volumio[13971]: info: Get:6 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libfftw3-double3 armhf 3.3.10-1 [325 kB] May 31 21:10:21 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:21.016-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:21 solhi-fi volumio[13971]: info: Get:7 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libfftw3-bin armhf 3.3.10-1 [40.8 kB] May 31 21:10:21 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:21.412-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:21 solhi-fi volumio[13971]: info: Get:8 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libfftw3-dev armhf 3.3.10-1 [827 kB] May 31 21:10:21 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:21.792-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:22 solhi-fi volumio[13971]: info: Get:9 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libimagequant0 armhf 2.17.0-1 [28.0 kB] May 31 21:10:22 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:22.207-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:22 solhi-fi volumio[13971]: info: Get:10 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libopusfile0 armhf 0.12-4 [38.2 kB] May 31 21:10:22 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:22.592-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:22 solhi-fi volumio[13971]: info: Get:11 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libportmidi0 armhf 1:217-6.1 [20.1 kB] May 31 21:10:22 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:22.998-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:23 solhi-fi volumio[13971]: info: Get:12 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] May 31 21:10:23 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:23.393-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:23 solhi-fi volumio[13971]: 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] May 31 21:10:23 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:23.821-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:24 solhi-fi volumio[13971]: 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] May 31 21:10:24 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:24.168-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:24 solhi-fi volumio[13971]: 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] May 31 21:10:24 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:24.584-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:25 solhi-fi volumio[13971]: info: Get:16 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-numpy armhf 1:1.24.2-1+deb12u1 [3827 kB] May 31 21:10:25 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:25.181-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Get:17 http://mirror.web-ster.com/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.278-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.284-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pygame armhf 2.1.2+dfsg-5 [1061 kB] May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.300-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Fetched 9480 kB in 8s (1156 kB/s) May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.807-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Selecting previously unselected package bzip2. May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.850-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: [620B blob data] May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.886-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Preparing to unpack .../00-bzip2_1.0.8-5+b2_armhf.deb ... May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.894-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:26 solhi-fi volumio[13971]: info: Unpacking bzip2 (1.0.8-5+b2) ... May 31 21:10:26 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:26.908-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Selecting previously unselected package file. May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.055-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Preparing to unpack .../01-file_1%3a5.44-3_armhf.deb ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.065-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Unpacking file (1:5.44-3) ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.071-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Selecting previously unselected package mailcap. May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.205-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Preparing to unpack .../02-mailcap_3.70+nmu1_all.deb ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.218-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Unpacking mailcap (3.70+nmu1) ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.229-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Selecting previously unselected package mime-support. May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.401-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Preparing to unpack .../03-mime-support_3.66_all.deb ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.406-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Unpacking mime-support (3.66) ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.416-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Selecting previously unselected package fonts-freefont-ttf. May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.480-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Preparing to unpack .../04-fonts-freefont-ttf_20120503-10_all.deb ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.488-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:27 solhi-fi volumio[13971]: info: Unpacking fonts-freefont-ttf (20120503-10) ... May 31 21:10:27 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:27.499-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Selecting previously unselected package libfftw3-double3:armhf. May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.113-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Preparing to unpack .../05-libfftw3-double3_3.3.10-1_armhf.deb ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.120-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.149-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Selecting previously unselected package libfftw3-bin. May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.309-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Preparing to unpack .../06-libfftw3-bin_3.3.10-1_armhf.deb ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.318-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Unpacking libfftw3-bin (3.3.10-1) ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.325-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Selecting previously unselected package libfftw3-dev:armhf. May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.458-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Preparing to unpack .../07-libfftw3-dev_3.3.10-1_armhf.deb ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.463-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.471-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Selecting previously unselected package libimagequant0:armhf. May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.837-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Preparing to unpack .../08-libimagequant0_2.17.0-1_armhf.deb ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.843-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.850-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Selecting previously unselected package libopusfile0:armhf. May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.952-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Preparing to unpack .../09-libopusfile0_0.12-4_armhf.deb ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.962-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:28 solhi-fi volumio[13971]: info: Unpacking libopusfile0:armhf (0.12-4) ... May 31 21:10:28 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:28.974-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package libportmidi0:armhf. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.091-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../10-libportmidi0_1%3a217-6.1_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.099-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.107-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package libraqm0:armhf. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.211-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../11-libraqm0_0.7.0-4.1_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.216-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.227-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.331-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../12-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.344-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.350-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.553-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../13-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.560-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.569-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.693-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../14-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.701-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.708-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Selecting previously unselected package python3-numpy. May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.858-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Preparing to unpack .../15-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.863-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:29 solhi-fi volumio[13971]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... May 31 21:10:29 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:29.879-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:36 solhi-fi volumio[13971]: info: Selecting previously unselected package python3-olefile. May 31 21:10:36 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:36.844-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:36 solhi-fi volumio[13971]: info: Preparing to unpack .../16-python3-olefile_0.46-3_all.deb ... May 31 21:10:36 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:36.854-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:36 solhi-fi volumio[13971]: info: Unpacking python3-olefile (0.46-3) ... May 31 21:10:36 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:36.865-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:36 solhi-fi volumio[13971]: info: Selecting previously unselected package python3-pil:armhf. May 31 21:10:36 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:36.994-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:36 solhi-fi volumio[13971]: info: Preparing to unpack .../17-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... May 31 21:10:37 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:37.003-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:37 solhi-fi volumio[13971]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... May 31 21:10:37 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:37.018-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:37 solhi-fi volumio[13971]: info: Selecting previously unselected package python3-pygame. May 31 21:10:37 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:37.935-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:37 solhi-fi volumio[13971]: info: Preparing to unpack .../18-python3-pygame_2.1.2+dfsg-5_armhf.deb ... May 31 21:10:37 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:37.945-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:37 solhi-fi volumio[13971]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... May 31 21:10:37 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:37.966-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:39 solhi-fi volumio[13971]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... May 31 21:10:39 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:39.811-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:39 solhi-fi volumio[13971]: info: Setting up python3-olefile (0.46-3) ... May 31 21:10:39 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:39.845-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up fonts-freefont-ttf (20120503-10) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.053-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up file (1:5.44-3) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.082-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up bzip2 (1.0.8-5+b2) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.112-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.146-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up libportmidi0:armhf (1:217-6.1) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.183-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up libimagequant0:armhf (2.17.0-1) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.213-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:40 solhi-fi volumio[13971]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... May 31 21:10:40 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:40.240-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.412-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.443-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up mailcap (3.70+nmu1) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.475-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up libopusfile0:armhf (0.12-4) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.553-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up mime-support (3.66) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.580-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up libfftw3-bin (3.3.10-1) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.618-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:42 solhi-fi volumio[13971]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... May 31 21:10:42 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:42.644-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.045-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.089-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.330-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.373-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Processing triggers for man-db (2.11.2-2) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.444-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: info: Processing triggers for fontconfig (2.14.1-4) ... May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.555-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi sudo[14759]: pam_unix(sudo:session): session closed for user root May 31 21:10:43 solhi-fi volumio[13971]: 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 May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.938-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi volumio[13971]: 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 May 31 21:10:43 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:43.941-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:43 solhi-fi sudo[15090]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh May 31 21:10:43 solhi-fi sudo[15090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:43 solhi-fi sudo[15090]: pam_unix(sudo:session): session closed for user root May 31 21:10:43 solhi-fi sudo[15092]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload May 31 21:10:43 solhi-fi sudo[15092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:43 solhi-fi systemd[1]: Reloading. May 31 21:10:44 solhi-fi systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 21:10:44 solhi-fi systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 31 21:10:44 solhi-fi sudo[15092]: pam_unix(sudo:session): session closed for user root May 31 21:10:44 solhi-fi sudo[14609]: pam_unix(sudo:session): session closed for user root May 31 21:10:44 solhi-fi volumio[13971]: Plugin install end detected on script May 31 21:10:44 solhi-fi volumio[13971]: info: Install script completed May 31 21:10:44 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:44.301-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:44 solhi-fi volumio[13971]: info: Adding reference to registry May 31 21:10:44 solhi-fi volumio[13971]: info: Done installing plugin. May 31 21:10:44 solhi-fi volumio5-onboarding[1622]: time=2026-05-31T21:10:44.315-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state May 31 21:10:44 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 21:10:44 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 21:10:44 solhi-fi volumio[13971]: info: Folder /tmp/plugins removed May 31 21:10:44 solhi-fi volumio[13971]: info: Folder /tmp/downloaded_plugin.zip removed May 31 21:10:44 solhi-fi volumio[13971]: info: Folder /data/temp removed May 31 21:10:46 solhi-fi volumio[13971]: info: Enabling plugin peppyspectrum May 31 21:10:46 solhi-fi volumio[13971]: info: Loading plugin "peppyspectrum"... May 31 21:10:46 solhi-fi volumio[13971]: info: Preparing to generate the ALSA configuration file May 31 21:10:46 solhi-fi volumio[13971]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 31 21:10:46 solhi-fi volumio[13971]: info: Reading ALSA contributions from plugins. May 31 21:10:46 solhi-fi volumio[13971]: info: Asound.conf file written May 31 21:10:46 solhi-fi sudo[15113]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 31 21:10:46 solhi-fi sudo[15113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:46 solhi-fi sudo[15113]: pam_unix(sudo:session): session closed for user root May 31 21:10:46 solhi-fi volumio[13971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 May 31 21:10:46 solhi-fi volumio[13971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 May 31 21:10:46 solhi-fi volumio[13971]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 May 31 21:10:46 solhi-fi volumio[13971]: info: Output device has changed, restarting MPD May 31 21:10:47 solhi-fi volumio[13971]: info: Output device has changed, restarting Shairport Sync May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:10:47 solhi-fi sudo[15119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:10:47 solhi-fi sudo[15119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi sudo[15121]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:10:47 solhi-fi sudo[15121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi sudo[15119]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi volumio[13971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:10:47 solhi-fi volumio[13971]: info: PLUGIN START: peppyspectrum May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 31 21:10:47 solhi-fi systemd[1]: Stopping mpd.service - Music Player Daemon... May 31 21:10:47 solhi-fi sudo[15129]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 May 31 21:10:47 solhi-fi sudo[15129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi volumio[13971]: info: Preparing to generate the ALSA configuration file May 31 21:10:47 solhi-fi volumio[13971]: info: Done. May 31 21:10:47 solhi-fi volumio[13971]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf May 31 21:10:47 solhi-fi volumio[13971]: info: Reading ALSA contributions from plugins. May 31 21:10:47 solhi-fi volumio[13971]: info: MPD Permissions set May 31 21:10:47 solhi-fi volumio[13971]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 31 21:10:47 solhi-fi sudo[15129]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi systemd[1]: mpd.service: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 21:10:47 solhi-fi systemd[1]: mpd.socket: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 21:10:47 solhi-fi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi (udev-worker)[15136]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: snd_dummy loaded May 31 21:10:47 solhi-fi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 21:10:47 solhi-fi systemd[1]: Starting mpd.service - Music Player Daemon... May 31 21:10:47 solhi-fi volumio[13971]: info: Starting Shairport Sync May 31 21:10:47 solhi-fi volumio[13971]: info: Asound.conf file unchanged, so no further update is needed May 31 21:10:47 solhi-fi volumio[13971]: info: Output device has changed, restarting MPD May 31 21:10:47 solhi-fi sudo[15145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:10:47 solhi-fi sudo[15145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi sudo[15147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 21:10:47 solhi-fi sudo[15147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi sudo[15147]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi volumio[13971]: info: Output device has changed, restarting Shairport Sync May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:10:47 solhi-fi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 21:10:47 solhi-fi systemd[1]: shairport-sync.service: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 21:10:47 solhi-fi systemd[1]: shairport-sync.service: Consumed 1.573s CPU time. May 31 21:10:47 solhi-fi sudo[15151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 21:10:47 solhi-fi sudo[15151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 21:10:47 solhi-fi sudo[15145]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi volumio[13971]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 21:10:47 solhi-fi volumio[13971]: info: MPD Permissions set May 31 21:10:47 solhi-fi volumio[13971]: info: Shairport-Sync Started May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi systemd[1]: mpd.service: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Stopped mpd.service - Music Player Daemon. May 31 21:10:47 solhi-fi systemd[1]: mpd.socket: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 31 21:10:47 solhi-fi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 31 21:10:47 solhi-fi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 31 21:10:47 solhi-fi systemd[1]: Starting mpd.service - Music Player Daemon... May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 21:10:47 solhi-fi volumio[13971]: info: Starting Shairport Sync May 31 21:10:47 solhi-fi sudo[15182]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 21:10:47 solhi-fi sudo[15182]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 21:10:47 solhi-fi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 31 21:10:47 solhi-fi systemd[1]: shairport-sync.service: Deactivated successfully. May 31 21:10:47 solhi-fi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 21:10:47 solhi-fi sudo[15167]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 21:10:47 solhi-fi sudo[15167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 31 21:10:47 solhi-fi sudo[15167]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 31 21:10:47 solhi-fi sudo[15182]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi volumio[13971]: info: Shairport-Sync Started May 31 21:10:47 solhi-fi mpd[15187]: 2026-05-31T21:10:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 21:10:47 solhi-fi systemd[1]: Started mpd.service - Music Player Daemon. May 31 21:10:47 solhi-fi sudo[15151]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi sudo[15121]: pam_unix(sudo:session): session closed for user root May 31 21:10:47 solhi-fi volumio[13971]: error: MPD error: The expression evaluated to a falsy value: May 31 21:10:47 solhi-fi volumio[13971]: assert.ok(self.idling) May 31 21:10:47 solhi-fi volumio[13971]: error: The expression evaluated to a falsy value: May 31 21:10:47 solhi-fi volumio[13971]: assert.ok(self.idling) May 31 21:10:47 solhi-fi volumio[13971]: error: updateQueue error: null May 31 21:10:55 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 21:10:55 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 21:10:58 solhi-fi volumio[13971]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:11:22 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 21:11:22 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 21:11:25 solhi-fi volumio[13971]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 21:11:35 solhi-fi volumio[13971]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] May 31 21:11:35 solhi-fi volumio[13971]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum May 31 21:11:36 solhi-fi volumio[13971]: --2026-05-31 21:11:35-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose May 31 21:11:36 solhi-fi volumio[13971]: Resolving github.com (github.com)... 140.82.113.4 May 31 21:11:36 solhi-fi volumio[13971]: Connecting to github.com (github.com)|140.82.113.4|:443... connected. May 31 21:11:36 solhi-fi volumio[13971]: HTTP request sent, awaiting response... 404 Not Found May 31 21:11:36 solhi-fi volumio[13971]: 2026-05-31 21:11:35 ERROR 404: Not Found. May 31 21:11:36 solhi-fi volumio[13971]: --2026-05-31 21:11:35-- http://a/ May 31 21:11:36 solhi-fi volumio[13971]: Resolving a (a)... failed: Device or resource busy. May 31 21:11:36 solhi-fi volumio[13971]: wget: unable to resolve host address ‘a’ May 31 21:11:36 solhi-fi volumio[13971]: --2026-05-31 21:11:35-- http://packet.zip/ May 31 21:11:36 solhi-fi volumio[13971]: Resolving packet.zip (packet.zip)... 162.255.119.167 May 31 21:11:36 solhi-fi volumio[13971]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. May 31 21:11:36 solhi-fi volumio[13971]: HTTP request sent, awaiting response... 302 Found May 31 21:11:36 solhi-fi volumio[13971]: Location: http://www.packet.zip/ [following] May 31 21:11:36 solhi-fi volumio[13971]: --2026-05-31 21:11:36-- http://www.packet.zip/ May 31 21:11:36 solhi-fi volumio[13971]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 May 31 21:11:36 solhi-fi volumio[13971]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. May 31 21:11:36 solhi-fi volumio[13971]: HTTP request sent, awaiting response... 403 Forbidden May 31 21:11:36 solhi-fi volumio[13971]: 2026-05-31 21:11:36 ERROR 403: Forbidden. May 31 21:11:36 solhi-fi volumio[13971]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove 'a': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:11:36 solhi-fi volumio[13971]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove 'a': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: /bin/rm: cannot remove 'packet.zip*': No such file or directory May 31 21:11:36 solhi-fi volumio[13971]: at checkExecSyncError (node:child_process:887:11) May 31 21:11:36 solhi-fi volumio[13971]: at execSync (node:child_process:959:15) May 31 21:11:36 solhi-fi volumio[13971]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 May 31 21:11:36 solhi-fi volumio[13971]: at new Promise () May 31 21:11:36 solhi-fi volumio[13971]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) May 31 21:11:36 solhi-fi volumio[13971]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) May 31 21:11:36 solhi-fi volumio[13971]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) May 31 21:11:36 solhi-fi volumio[13971]: at Socket.emit (node:events:514:28) May 31 21:11:36 solhi-fi volumio[13971]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 31 21:11:36 solhi-fi volumio[13971]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { May 31 21:11:36 solhi-fi volumio[13971]: status: 1, May 31 21:11:36 solhi-fi volumio[13971]: signal: null, May 31 21:11:36 solhi-fi volumio[13971]: output: [ May 31 21:11:36 solhi-fi volumio[13971]: null, May 31 21:11:36 solhi-fi volumio[13971]: , May 31 21:11:36 solhi-fi volumio[13971]: May 31 21:11:36 solhi-fi volumio[13971]: ], May 31 21:11:36 solhi-fi volumio[13971]: pid: 15291, May 31 21:11:36 solhi-fi volumio[13971]: stdout: , May 31 21:11:36 solhi-fi volumio[13971]: stderr: May 31 21:11:36 solhi-fi volumio[13971]: } May 31 21:11:36 solhi-fi volumio[13971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 21:11:36 solhi-fi sudo[15307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-31 21:10' May 31 21:11:36 solhi-fi sudo[15307]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"