Feb 10 20:32:11 volumio volumio[1421]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/peppymeterbasic/volumio/bookworm/armhf Feb 10 20:32:11 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:11.930+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:13 volumio volumio[1421]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/peppymeterbasic/volumio/bookworm/armhf Feb 10 20:32:13 volumio volumio[1421]: info: Folder /tmp/plugins removed Feb 10 20:32:13 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:13.968+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.056+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Check plugin dependencies Feb 10 20:32:14 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.078+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Checking if plugin already exists Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Rename folder Feb 10 20:32:14 volumio volumio[1421]: info: Folder /tmp/downloaded_plugin.zip removed Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.097+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Move to category Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.148+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Checking if install.sh is present Feb 10 20:32:14 volumio volumio[1421]: info: Executing install.sh Feb 10 20:32:14 volumio sudo[2815]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppymeterbasic/install.sh Feb 10 20:32:14 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:14 volumio volumio[1421]: info: Installing peppyalsa plugin dependencies Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.182+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio sudo[2819]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Feb 10 20:32:14 volumio sudo[2819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:14 volumio volumio[1421]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.682+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:14 volumio volumio[1421]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Feb 10 20:32:14 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:14.692+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:16 volumio volumio[1421]: info: Reading package lists... Feb 10 20:32:16 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:16.841+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:16 volumio sudo[2819]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:16 volumio volumio[1421]: info: cloning peppymeter repo Feb 10 20:32:16 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:16.894+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:22 volumio sudo[3003]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Feb 10 20:32:22 volumio sudo[3003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:22 volumio sudo[3003]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:22 volumio sudo[3005]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Feb 10 20:32:22 volumio sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:22 volumio sudo[3005]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:22 volumio volumio[1421]: info: installing apt packages Feb 10 20:32:22 volumio sudo[3007]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -yf install python3-pygame python3-pip python3-dev libjpeg-dev zlib1g-dev libfftw3-dev Feb 10 20:32:22 volumio sudo[3007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:22 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:22.722+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:22 volumio volumio[1421]: info: Reading package lists... Feb 10 20:32:22 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:22.799+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: Building dependency tree... Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.167+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: Reading state information... Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.184+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: python3-pip is already the newest version (23.0.1+dfsg-1+rpt1). Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.827+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: python3-dev is already the newest version (3.11.2-1). Feb 10 20:32:23 volumio volumio[1421]: info: libjpeg-dev is already the newest version (1:2.1.5-2). Feb 10 20:32:23 volumio volumio[1421]: info: zlib1g-dev is already the newest version (1:1.2.13.dfsg-1+rpt1). Feb 10 20:32:23 volumio volumio[1421]: info: The following additional packages will be installed: Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.842+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.842+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.842+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.843+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: libfftw3-bin libfftw3-double3 libopusfile0 libportmidi0 libsdl2-image-2.0-0 Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.861+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: libsdl2-mixer-2.0-0 libsdl2-ttf-2.0-0 python3-numpy Feb 10 20:32:23 volumio volumio[1421]: info: Suggested packages: Feb 10 20:32:23 volumio volumio[1421]: info: libfftw3-doc gcc gfortran python3-pytest python-pygame-doc timidity Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.873+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.874+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.874+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: The following NEW packages will be installed: Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.898+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio[1421]: info: libfftw3-bin libfftw3-dev libfftw3-double3 libopusfile0 libportmidi0 Feb 10 20:32:23 volumio volumio[1421]: info: libsdl2-image-2.0-0 libsdl2-mixer-2.0-0 libsdl2-ttf-2.0-0 python3-numpy Feb 10 20:32:23 volumio volumio[1421]: info: python3-pygame Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.915+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.916+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:23 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:23.916+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:24 volumio volumio[1421]: info: 0 upgraded, 10 newly installed, 0 to remove and 26 not upgraded. Feb 10 20:32:24 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:24.530+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:24 volumio volumio[1421]: info: Need to get 0 B/6289 kB of archives. Feb 10 20:32:24 volumio volumio[1421]: info: After this operation, 36.5 MB of additional disk space will be used. Feb 10 20:32:24 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:24.537+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:24 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:24.538+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:24 volumio volumio[1421]: info: Selecting previously unselected package libfftw3-double3:armhf. Feb 10 20:32:24 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:24.599+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: [620B blob data] Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.219+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Preparing to unpack .../0-libfftw3-double3_3.3.10-1_armhf.deb ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.225+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.243+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Selecting previously unselected package libfftw3-bin. Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.386+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Preparing to unpack .../1-libfftw3-bin_3.3.10-1_armhf.deb ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.393+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Unpacking libfftw3-bin (3.3.10-1) ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.400+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Selecting previously unselected package libfftw3-dev:armhf. Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.513+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Preparing to unpack .../2-libfftw3-dev_3.3.10-1_armhf.deb ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.519+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.526+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Selecting previously unselected package libopusfile0:armhf. Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.801+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Preparing to unpack .../3-libopusfile0_0.12-4_armhf.deb ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.808+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Unpacking libopusfile0:armhf (0.12-4) ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.819+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Selecting previously unselected package libportmidi0:armhf. Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.912+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Preparing to unpack .../4-libportmidi0_1%3a217-6.1_armhf.deb ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.923+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:25 volumio volumio[1421]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... Feb 10 20:32:25 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:25.931+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.017+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Preparing to unpack .../5-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.025+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.042+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.126+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Preparing to unpack .../6-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.133+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.148+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.247+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Preparing to unpack .../7-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.262+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.268+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Selecting previously unselected package python3-numpy. Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.344+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Preparing to unpack .../8-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.352+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:26 volumio volumio[1421]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... Feb 10 20:32:26 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:26.358+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:31 volumio volumio[1421]: info: Selecting previously unselected package python3-pygame. Feb 10 20:32:31 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:31.106+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:31 volumio volumio[1421]: info: Preparing to unpack .../9-python3-pygame_2.1.2+dfsg-5_armhf.deb ... Feb 10 20:32:31 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:31.115+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:31 volumio volumio[1421]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... Feb 10 20:32:31 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:31.129+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:32 volumio volumio[1421]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Feb 10 20:32:32 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:32.401+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:32 volumio volumio[1421]: info: Setting up libportmidi0:armhf (1:217-6.1) ... Feb 10 20:32:32 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:32.434+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:32 volumio volumio[1421]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... Feb 10 20:32:32 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:32.461+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.672+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.705+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up libopusfile0:armhf (0.12-4) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.742+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up libfftw3-bin (3.3.10-1) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.773+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.811+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:35 volumio volumio[1421]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... Feb 10 20:32:35 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:35.858+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:36 volumio volumio[1421]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... Feb 10 20:32:36 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:36.172+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:36 volumio volumio[1421]: info: Processing triggers for man-db (2.11.2-2) ... Feb 10 20:32:36 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:36.199+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:36 volumio volumio[1421]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Feb 10 20:32:36 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:36.368+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:37 volumio sudo[3007]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:37 volumio sudo[3199]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Feb 10 20:32:37 volumio sudo[3199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:37 volumio systemd[1]: Reloading. Feb 10 20:32:37 volumio sudo[3199]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:37 volumio volumio[1421]: 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 Feb 10 20:32:37 volumio volumio[1421]: 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 Feb 10 20:32:37 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:37.667+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:37 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:37.670+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:38 volumio sudo[3225]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Feb 10 20:32:38 volumio sudo[3225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:38 volumio sudo[3225]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:38 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:38 volumio volumio[1421]: Plugin install end detected on script Feb 10 20:32:38 volumio volumio[1421]: info: Install script completed Feb 10 20:32:38 volumio volumio[1421]: info: Adding reference to registry Feb 10 20:32:38 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:38.757+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:38 volumio volumio5-onboarding[2239]: time=2026-02-10T20:32:38.763+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 10 20:32:38 volumio volumio[1421]: info: Done installing plugin. Feb 10 20:32:38 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 10 20:32:38 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 10 20:32:38 volumio volumio[1421]: info: Folder /tmp/plugins removed Feb 10 20:32:38 volumio volumio[1421]: info: Folder /tmp/downloaded_plugin.zip removed Feb 10 20:32:38 volumio volumio[1421]: info: Folder /data/temp removed Feb 10 20:32:40 volumio volumio[1421]: info: Enabling plugin peppymeterbasic Feb 10 20:32:40 volumio volumio[1421]: info: Loading plugin "peppymeterbasic"... Feb 10 20:32:41 volumio volumio[1421]: info: Preparing to generate the ALSA configuration file Feb 10 20:32:41 volumio volumio[1421]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 20:32:41 volumio volumio[1421]: info: Reading ALSA contributions from plugins. Feb 10 20:32:41 volumio volumio[1421]: info: Asound.conf file written Feb 10 20:32:41 volumio sudo[3247]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 10 20:32:41 volumio sudo[3247]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio sudo[3247]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio volumio[1421]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 10 20:32:41 volumio volumio[1421]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 10 20:32:41 volumio volumio[1421]: info: Output device has changed, restarting MPD Feb 10 20:32:41 volumio volumio[1421]: info: Output device has changed, restarting Shairport Sync Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 20:32:41 volumio sudo[3253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 20:32:41 volumio sudo[3253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio sudo[3253]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio sudo[3255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 20:32:41 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio volumio[1421]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 20:32:41 volumio volumio[1421]: info: PLUGIN START: peppymeterbasic Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 10 20:32:41 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 10 20:32:41 volumio volumio[1421]: info: Preparing to generate the ALSA configuration file Feb 10 20:32:41 volumio sudo[3264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Feb 10 20:32:41 volumio sudo[3264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio volumio[1421]: info: Done. Feb 10 20:32:41 volumio volumio[1421]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 10 20:32:41 volumio volumio[1421]: info: Reading ALSA contributions from plugins. Feb 10 20:32:41 volumio volumio[1421]: info: MPD Permissions set Feb 10 20:32:41 volumio volumio[1421]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio sudo[3264]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: snd_dummy loaded Feb 10 20:32:41 volumio volumio[1421]: info: Starting Shairport Sync Feb 10 20:32:41 volumio volumio[1421]: info: Asound.conf file unchanged, so no further update is needed Feb 10 20:32:41 volumio volumio[1421]: info: Output device has changed, restarting MPD Feb 10 20:32:41 volumio sudo[3278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 20:32:41 volumio sudo[3278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio volumio[1421]: info: Output device has changed, restarting Shairport Sync Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 20:32:41 volumio sudo[3281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 10 20:32:41 volumio sudo[3281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio sudo[3281]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 20:32:41 volumio volumio[1421]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 10 20:32:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 20:32:41 volumio systemd[1]: mpd.service: Consumed 2.334s CPU time. Feb 10 20:32:41 volumio sudo[3283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 10 20:32:41 volumio sudo[3283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 20:32:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 20:32:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 20:32:41 volumio volumio[1421]: info: MPD Permissions set Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 20:32:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 10 20:32:41 volumio volumio[1421]: info: Starting Shairport Sync Feb 10 20:32:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 20:32:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 20:32:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 20:32:41 volumio systemd[1]: shairport-sync.service: Consumed 1.835s CPU time. Feb 10 20:32:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 20:32:41 volumio sudo[3301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 10 20:32:41 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 10 20:32:41 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 10 20:32:41 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 10 20:32:41 volumio sudo[3278]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 10 20:32:41 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 10 20:32:41 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 10 20:32:41 volumio volumio[1421]: info: Shairport-Sync Started Feb 10 20:32:41 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 10 20:32:41 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 10 20:32:41 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 10 20:32:41 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 10 20:32:41 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 20:32:41 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 10 20:32:41 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio volumio[1421]: info: Shairport-Sync Started Feb 10 20:32:41 volumio sudo[3305]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 10 20:32:41 volumio sudo[3305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 10 20:32:41 volumio sudo[3305]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio mpd[3335]: 2026-02-10T20:32:41 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 10 20:32:41 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 10 20:32:41 volumio sudo[3283]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:41 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root Feb 10 20:32:42 volumio volumio[1421]: error: MPD error: The expression evaluated to a falsy value: Feb 10 20:32:42 volumio volumio[1421]: assert.ok(self.idling) Feb 10 20:32:42 volumio volumio[1421]: error: The expression evaluated to a falsy value: Feb 10 20:32:42 volumio volumio[1421]: assert.ok(self.idling) Feb 10 20:32:42 volumio volumio[1421]: error: updateQueue error: null Feb 10 20:32:49 volumio volumio[1421]: info: CoreCommandRouter::volumioGetState Feb 10 20:32:49 volumio volumio[1421]: info: CorePlayQueue::getTrack 0 Feb 10 20:32:57 volumio volumio[1421]: info: CoreCommandRouter::volumioPause Feb 10 20:32:57 volumio volumio[1421]: info: CoreStateMachine::pause Feb 10 20:32:57 volumio volumio[1421]: info: CoreStateMachine::stPlaybackTimer Feb 10 20:32:57 volumio volumio[1421]: info: CoreStateMachine::servicePause Feb 10 20:32:57 volumio volumio[1421]: info: CorePlayQueue::getTrack 0 Feb 10 20:32:57 volumio volumio[1421]: info: CoreCommandRouter::servicePause Feb 10 20:32:57 volumio volumio[1421]: info: ControllerMpd::pause Feb 10 20:32:57 volumio volumio[1421]: verbose: ControllerMpd::sendMpdCommand pause Feb 10 20:32:57 volumio volumio[1421]: info: sendMpdCommand pause took 1 milliseconds Feb 10 20:33:05 volumio volumio[1421]: info: CoreCommandRouter::volumioPause Feb 10 20:33:05 volumio volumio[1421]: info: CoreStateMachine::pause Feb 10 20:33:19 volumio volumio[1421]: info: CoreCommandRouter::volumioNext Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::next Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::stop Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::updateTrackBlock Feb 10 20:33:19 volumio volumio[1421]: info: CorePlayQueue::getTrackBlock Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::stPlaybackTimer Feb 10 20:33:19 volumio volumio[1421]: info: CoreStateMachine::pushState Feb 10 20:33:19 volumio volumio[1421]: info: CorePlayQueue::getTrack 0 Feb 10 20:33:19 volumio volumio[1421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 10 20:33:19 volumio volumio[1421]: info: CoreCommandRouter::volumioPushState Feb 10 20:33:19 volumio volumio[1421]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 20:33:19 volumio volumio[1421]: Error: channel closed Feb 10 20:33:19 volumio volumio[1421]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:71:12) Feb 10 20:33:19 volumio volumio[1421]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 10 20:33:19 volumio volumio[1421]: at /volumio/app/index.js:326:81 Feb 10 20:33:19 volumio volumio[1421]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 10 20:33:19 volumio volumio[1421]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 10 20:33:19 volumio volumio[1421]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 10 20:33:19 volumio volumio[1421]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 10 20:33:19 volumio volumio[1421]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1256:12) Feb 10 20:33:19 volumio volumio[1421]: at CoreStateMachine.next (/volumio/app/statemachine.js:1180:12) Feb 10 20:33:19 volumio volumio[1421]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Feb 10 20:33:19 volumio volumio[1421]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Feb 10 20:33:19 volumio volumio[1421]: at Socket.emit (node:events:514:28) Feb 10 20:33:19 volumio volumio[1421]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 10 20:33:19 volumio volumio[1421]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 10 20:33:19 volumio volumio[1421]: code: 'GenericFailure' Feb 10 20:33:19 volumio volumio[1421]: } Feb 10 20:33:19 volumio volumio[1421]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 10 20:33:19 volumio sudo[3479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-10 20:32' Feb 10 20:33:19 volumio sudo[3479]: 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="f8b25437696d030ba01136ff33a129a42b97a4aa" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Feb 10 17:57:40 UTC 2026" VOLUMIO_VERSION="4.097" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67b01b7f4046986651bf7f5bfadaefdf"