Mar 27 19:40:00 volumio volumio[1269]: info: Selecting previously unselected package libexpat1-dev:armhf. Mar 27 19:40:00 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:00.708+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:00 volumio volumio[1269]: info: Preparing to unpack .../15-libexpat1-dev_2.5.0-1+deb12u2_armhf.deb ... Mar 27 19:40:00 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:00.720+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:00 volumio volumio[1269]: info: Unpacking libexpat1-dev:armhf (2.5.0-1+deb12u2) ... Mar 27 19:40:00 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:00.740+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:00 volumio volumio[1269]: info: Selecting previously unselected package libfftw3-double3:armhf. Mar 27 19:40:00 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:00.989+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:00 volumio volumio[1269]: info: Preparing to unpack .../16-libfftw3-double3_3.3.10-1_armhf.deb ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.005+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.018+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Selecting previously unselected package libfftw3-bin. Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.254+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Preparing to unpack .../17-libfftw3-bin_3.3.10-1_armhf.deb ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.272+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Unpacking libfftw3-bin (3.3.10-1) ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.289+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Selecting previously unselected package libfftw3-dev:armhf. Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.490+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Preparing to unpack .../18-libfftw3-dev_3.3.10-1_armhf.deb ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.503+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:01 volumio volumio[1269]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... Mar 27 19:40:01 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:01.527+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Selecting previously unselected package libjpeg62-turbo-dev:armhf. Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.079+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Preparing to unpack .../19-libjpeg62-turbo-dev_1%3a2.1.5-2_armhf.deb ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.094+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Unpacking libjpeg62-turbo-dev:armhf (1:2.1.5-2) ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.111+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Selecting previously unselected package libjpeg-dev:armhf. Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.319+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Preparing to unpack .../20-libjpeg-dev_1%3a2.1.5-2_armhf.deb ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.335+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Unpacking libjpeg-dev:armhf (1:2.1.5-2) ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.351+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Selecting previously unselected package libopusfile0:armhf. Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.475+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Preparing to unpack .../21-libopusfile0_0.12-4_armhf.deb ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.491+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Unpacking libopusfile0:armhf (0.12-4) ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.507+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Selecting previously unselected package libportmidi0:armhf. Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.668+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Preparing to unpack .../22-libportmidi0_1%3a217-6.1_armhf.deb ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.685+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.701+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Selecting previously unselected package zlib1g-dev:armhf. Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.848+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Preparing to unpack .../23-zlib1g-dev_1%3a1.2.13.dfsg-1+rpt1_armhf.deb ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.860+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:02 volumio volumio[1269]: info: Unpacking zlib1g-dev:armhf (1:1.2.13.dfsg-1+rpt1) ... Mar 27 19:40:02 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:02.880+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:03 volumio volumio[1269]: info: Selecting previously unselected package libpython3.11-dev:armhf. Mar 27 19:40:03 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:03.092+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:03 volumio volumio[1269]: info: Preparing to unpack .../24-libpython3.11-dev_3.11.2-6+deb12u6_armhf.deb ... Mar 27 19:40:03 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:03.107+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:03 volumio volumio[1269]: info: Unpacking libpython3.11-dev:armhf (3.11.2-6+deb12u6) ... Mar 27 19:40:03 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:03.124+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:05 volumio volumio[1269]: info: Selecting previously unselected package libpython3-dev:armhf. Mar 27 19:40:05 volumio volumio[1269]: info: Preparing to unpack .../25-libpython3-dev_3.11.2-1_armhf.deb ... Mar 27 19:40:05 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:05.856+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:05 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:05.868+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:05 volumio volumio[1269]: info: Unpacking libpython3-dev:armhf (3.11.2-1) ... Mar 27 19:40:05 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:05.883+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.025+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Preparing to unpack .../26-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.041+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.070+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.215+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Preparing to unpack .../27-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.234+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.250+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.418+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Preparing to unpack .../28-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.439+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.456+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Selecting previously unselected package manpages-dev. Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.620+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Preparing to unpack .../29-manpages-dev_6.03-2_all.deb ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.636+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:06 volumio volumio[1269]: info: Unpacking manpages-dev (6.03-2) ... Mar 27 19:40:06 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:06.658+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Selecting previously unselected package python3.11-dev. Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.188+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Preparing to unpack .../30-python3.11-dev_3.11.2-6+deb12u6_armhf.deb ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.202+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Unpacking python3.11-dev (3.11.2-6+deb12u6) ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.218+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Selecting previously unselected package python3-dev. Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.362+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Preparing to unpack .../31-python3-dev_3.11.2-1_armhf.deb ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.375+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Unpacking python3-dev (3.11.2-1) ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.397+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Selecting previously unselected package python3-numpy. Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.546+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Preparing to unpack .../32-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.560+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:13 volumio volumio[1269]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... Mar 27 19:40:13 volumio volumio5-onboarding[3100]: time=2026-03-27T19:40:13.582+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:15 volumio volumio5-onboarding[3100]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Mar 27 19:40:15 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 27 19:40:15 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 27 19:40:15 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 13. Mar 27 19:40:15 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:40:15 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:40:16 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:16.020+03:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 27 19:40:16 volumio volumio[1269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Mar 27 19:40:16 volumio volumio[1269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 8 Mar 27 19:40:16 volumio volumio[1269]: info: Received Get System Info Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:40:16 volumio volumio[1269]: info: Discovery: Getting this device information Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::volumioGetState Mar 27 19:40:16 volumio volumio[1269]: info: CorePlayQueue::getTrack 0 Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:40:16 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:16.052+03:00 level=INFO msg="system info for bb8ecbf407501082e051e218ebf4334e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 27 19:40:16 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:16.080+03:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 27 19:40:16 volumio volumio[1269]: info: Received Get System Info Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:40:16 volumio volumio[1269]: info: Discovery: Getting this device information Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::volumioGetState Mar 27 19:40:16 volumio volumio[1269]: info: CorePlayQueue::getTrack 0 Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:40:16 volumio volumio-remote-updater[819]: Test mode disabled Mar 27 19:40:16 volumio volumio-remote-updater[819]: Alpha mode disabled Mar 27 19:40:16 volumio volumio-remote-updater[819]: Alpha legacy test mode disabled Mar 27 19:40:16 volumio volumio[1269]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n\n

NEW ADDITIONS

\n\n","title":"Update v4.119","updateavailable":true} Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:16 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 19:40:21 volumio volumio[1269]: info: Selecting previously unselected package python3-pygame. Mar 27 19:40:21 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:21.140+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:21 volumio volumio[1269]: info: Preparing to unpack .../33-python3-pygame_2.1.2+dfsg-5_armhf.deb ... Mar 27 19:40:21 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:21.158+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:21 volumio volumio[1269]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... Mar 27 19:40:21 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:21.177+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.246+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up libabsl20220623:armhf (20220623.1-1+rpi1+deb12u2) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.286+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up manpages (6.03-2) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.323+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up fonts-freefont-ttf (20120503-10) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.369+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up linux-libc-dev (1:6.12.75-1+rpt1~bookworm) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.406+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up libtirpc-dev:armhf (1.3.3+ds-1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.437+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up rpcsvc-proto (1.4.3-1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.474+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up libportmidi0:armhf (1:217-6.1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.508+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up libgav1-1:armhf (0.18.0-1+rpi1+b1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.547+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:23 volumio volumio[1269]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... Mar 27 19:40:23 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:23.583+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:26 volumio volumio[1269]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.715+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libnsl-dev:armhf (1.3.0-2) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.750+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libcrypt-dev:armhf (1:4.4.33-2) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.780+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.845+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libc-dev-bin (2.36-9+rpt2+deb12u13) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.877+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libyuv0:armhf (0.0~git20230123.b2528b0-1) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.920+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up libopusfile0:armhf (0.12-4) ... Mar 27 19:40:29 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:29.956+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:29 volumio volumio[1269]: info: Setting up manpages-dev (6.03-2) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.002+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libavif15:armhf (0.11.1-1+deb12u1) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.048+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libfftw3-bin (3.3.10-1) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.083+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.117+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libgd3:armhf (2.3.3-9) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.147+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.188+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libc6-dev:armhf (2.36-9+rpt2+deb12u13) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.767+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.800+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libc-devtools (2.36-9+rpt2+deb12u13) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.829+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libexpat1-dev:armhf (2.5.0-1+deb12u2) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.864+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libjpeg62-turbo-dev:armhf (1:2.1.5-2) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.899+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up zlib1g-dev:armhf (1:1.2.13.dfsg-1+rpt1) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.927+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libjpeg-dev:armhf (1:2.1.5-2) ... Mar 27 19:40:30 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:30.965+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:30 volumio volumio[1269]: info: Setting up libpython3.11-dev:armhf (3.11.2-6+deb12u6) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.005+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:31 volumio volumio[1269]: info: Setting up libpython3-dev:armhf (3.11.2-1) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.039+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:31 volumio volumio[1269]: info: Setting up python3.11-dev (3.11.2-6+deb12u6) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.069+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:31 volumio volumio[1269]: info: Setting up python3-dev (3.11.2-1) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.109+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:31 volumio volumio[1269]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.149+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:31 volumio volumio[1269]: info: Processing triggers for man-db (2.11.2-2) ... Mar 27 19:40:31 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:31.497+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:33 volumio volumio[1269]: info: Processing triggers for fontconfig (2.14.1-4) ... Mar 27 19:40:33 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:33.320+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:35 volumio sudo[3051]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:36 volumio sudo[3590]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Mar 27 19:40:36 volumio sudo[3590]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 19:40:37 volumio systemd[1]: Reloading. Mar 27 19:40:38 volumio sudo[3590]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:38 volumio volumio[1269]: 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 Mar 27 19:40:38 volumio volumio[1269]: 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 Mar 27 19:40:38 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:38.059+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:38 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:38.072+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:40 volumio sudo[3613]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Mar 27 19:40:40 volumio sudo[3613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 19:40:40 volumio sudo[3613]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:40 volumio sudo[2864]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:40 volumio volumio[1269]: Plugin install end detected on script Mar 27 19:40:40 volumio volumio[1269]: info: Install script completed Mar 27 19:40:40 volumio volumio[1269]: info: Adding reference to registry Mar 27 19:40:40 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:40.099+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:40 volumio volumio[1269]: info: Done installing plugin. Mar 27 19:40:40 volumio volumio5-onboarding[3500]: time=2026-03-27T19:40:40.112+03:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 19:40:40 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:40 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 19:40:40 volumio volumio[1269]: info: Folder /tmp/plugins removed Mar 27 19:40:40 volumio volumio[1269]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 19:40:40 volumio volumio[1269]: info: Folder /data/temp removed Mar 27 19:40:46 volumio volumio5-onboarding[3500]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Mar 27 19:40:46 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 27 19:40:46 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 27 19:40:46 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 14. Mar 27 19:40:46 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:40:46 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:40:46 volumio volumio5-onboarding[3631]: time=2026-03-27T19:40:46.336+03:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 27 19:40:46 volumio volumio[1269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 27 19:40:46 volumio volumio[1269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 7 Mar 27 19:40:46 volumio volumio[1269]: info: Received Get System Info Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:40:46 volumio volumio[1269]: info: Discovery: Getting this device information Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::volumioGetState Mar 27 19:40:46 volumio volumio[1269]: info: CorePlayQueue::getTrack 0 Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:40:46 volumio volumio5-onboarding[3631]: time=2026-03-27T19:40:46.361+03:00 level=INFO msg="system info for bb8ecbf407501082e051e218ebf4334e" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.103 Mar 27 19:40:46 volumio volumio5-onboarding[3631]: time=2026-03-27T19:40:46.386+03:00 level=INFO msg="bootstrapping state" hasInternet=true Mar 27 19:40:46 volumio volumio[1269]: info: Received Get System Info Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 19:40:46 volumio volumio[1269]: info: Discovery: Getting this device information Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::volumioGetState Mar 27 19:40:46 volumio volumio[1269]: info: CorePlayQueue::getTrack 0 Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 19:40:46 volumio volumio-remote-updater[819]: Test mode disabled Mar 27 19:40:46 volumio volumio-remote-updater[819]: Alpha mode disabled Mar 27 19:40:46 volumio volumio-remote-updater[819]: Alpha legacy test mode disabled Mar 27 19:40:46 volumio volumio[1269]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

\n
    \n
  • Fix for missing PWM backlight module
    • \n
  • Fix for crash loop on Onboarding plugin
    • \n
\n

NEW ADDITIONS

\n
    \n
  • Bump RPi/CM4 kernel to 6.12.74
    • \n
  • Bump Vivaldi to 7.5 for Kiosk Mode
    • \n
\n","title":"Update v4.119","updateavailable":true} Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:46 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 19:40:47 volumio sshd[2658]: pam_unix(sshd:session): session closed for user volumio Mar 27 19:40:47 volumio systemd[1]: session-3.scope: Deactivated successfully. Mar 27 19:40:47 volumio systemd[1]: session-3.scope: Consumed 13.744s CPU time. Mar 27 19:40:47 volumio systemd-logind[807]: Session 3 logged out. Waiting for processes to exit. Mar 27 19:40:47 volumio systemd-logind[807]: Removed session 3. Mar 27 19:40:50 volumio volumio[1269]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Mar 27 19:40:53 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 19:40:53 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 19:40:56 volumio volumio[1269]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Mar 27 19:40:57 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Mar 27 19:40:57 volumio systemd[2678]: Activating special unit exit.target... Mar 27 19:40:57 volumio systemd[2678]: Stopped target default.target - Main User Target. Mar 27 19:40:57 volumio systemd[2678]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Mar 27 19:40:57 volumio mpris-proxy[2694]: org.bluez appeared Mar 27 19:40:57 volumio mpris-proxy[2694]: Bluetooth Adapter /org/bluez/hci0 found Mar 27 19:40:57 volumio mpris-proxy[2694]: Bluetooth Adapter /org/bluez/hci0 removed Mar 27 19:40:57 volumio mpris-proxy[2694]: org.bluez disappeared Mar 27 19:40:57 volumio systemd[2678]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Mar 27 19:40:57 volumio systemd[2678]: Stopping dbus.service - D-Bus User Message Bus... Mar 27 19:40:57 volumio systemd[2678]: Stopped dbus.service - D-Bus User Message Bus. Mar 27 19:40:57 volumio systemd[2678]: Removed slice session.slice - User Core Session Slice. Mar 27 19:40:57 volumio systemd[2678]: Stopped target basic.target - Basic System. Mar 27 19:40:57 volumio systemd[2678]: Stopped target paths.target - Paths. Mar 27 19:40:57 volumio systemd[2678]: Stopped target sockets.target - Sockets. Mar 27 19:40:57 volumio systemd[2678]: Stopped target timers.target - Timers. Mar 27 19:40:57 volumio systemd[2678]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 27 19:40:57 volumio systemd[2678]: Closed dirmngr.socket - GnuPG network certificate management daemon. Mar 27 19:40:57 volumio systemd[2678]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 27 19:40:57 volumio systemd[2678]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 27 19:40:57 volumio systemd[2678]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 27 19:40:57 volumio systemd[2678]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 27 19:40:57 volumio systemd[2678]: Removed slice app.slice - User Application Slice. Mar 27 19:40:57 volumio systemd[2678]: Reached target shutdown.target - Shutdown. Mar 27 19:40:57 volumio systemd[2678]: Finished systemd-exit.service - Exit the Session. Mar 27 19:40:57 volumio systemd[2678]: Reached target exit.target - Exit the Session. Mar 27 19:40:57 volumio systemd[1]: user@1000.service: Deactivated successfully. Mar 27 19:40:57 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Mar 27 19:40:57 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Mar 27 19:40:57 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Mar 27 19:40:57 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Mar 27 19:40:57 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Mar 27 19:40:57 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Mar 27 19:40:57 volumio systemd[1]: user-1000.slice: Consumed 14.341s CPU time. Mar 27 19:40:58 volumio volumio[1269]: info: Enabling plugin peppymeterbasic Mar 27 19:40:58 volumio volumio[1269]: info: Loading plugin "peppymeterbasic"... Mar 27 19:40:58 volumio volumio[1269]: info: Preparing to generate the ALSA configuration file Mar 27 19:40:58 volumio volumio[1269]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 19:40:58 volumio volumio[1269]: info: Reading ALSA contributions from plugins. Mar 27 19:40:58 volumio volumio[1269]: info: Asound.conf file written Mar 27 19:40:58 volumio sudo[3662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 27 19:40:58 volumio sudo[3662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:58 volumio sudo[3662]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:58 volumio volumio[1269]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 27 19:40:58 volumio volumio[1269]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 27 19:40:58 volumio volumio[1269]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 27 19:40:58 volumio volumio[1269]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 27 19:40:58 volumio volumio[1269]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Mar 27 19:40:58 volumio volumio[1269]: info: Output device has changed, restarting MPD Mar 27 19:40:58 volumio volumio[1269]: info: Output device has changed, restarting Shairport Sync Mar 27 19:40:58 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:58 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 19:40:58 volumio sudo[3668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 19:40:58 volumio sudo[3668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:58 volumio sudo[3668]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:58 volumio sudo[3671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 19:40:58 volumio sudo[3671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:58 volumio volumio[1269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 19:40:58 volumio volumio[1269]: info: PLUGIN START: peppymeterbasic Mar 27 19:40:58 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 27 19:40:58 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 27 19:40:58 volumio volumio[1269]: info: Preparing to generate the ALSA configuration file Mar 27 19:40:58 volumio volumio[1269]: info: Done. Mar 27 19:40:58 volumio volumio[1269]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Mar 27 19:40:58 volumio volumio[1269]: info: Reading ALSA contributions from plugins. Mar 27 19:40:59 volumio volumio[1269]: info: MPD Permissions set Mar 27 19:40:59 volumio volumio[1269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio sudo[3678]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Mar 27 19:40:59 volumio sudo[3678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: Asound.conf file unchanged, so no further update is needed Mar 27 19:40:59 volumio volumio[1269]: info: Output device has changed, restarting MPD Mar 27 19:40:59 volumio volumio[1269]: info: Output device has changed, restarting Shairport Sync Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 19:40:59 volumio sudo[3678]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:59 volumio sudo[3690]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 27 19:40:59 volumio sudo[3688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 27 19:40:59 volumio sudo[3688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:59 volumio sudo[3690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:59 volumio sudo[3688]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:59 volumio volumio[1269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 27 19:40:59 volumio volumio[1269]: info: Starting Shairport Sync Mar 27 19:40:59 volumio volumio[1269]: info: MPD Permissions set Mar 27 19:40:59 volumio volumio[1269]: info: snd_dummy loaded Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio sudo[3700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 19:40:59 volumio sudo[3700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 27 19:40:59 volumio volumio[1269]: info: Starting Shairport Sync Mar 27 19:40:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 19:40:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 19:40:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 19:40:59 volumio systemd[1]: shairport-sync.service: Consumed 2.441s CPU time. Mar 27 19:40:59 volumio sudo[3715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 27 19:40:59 volumio sudo[3715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 19:40:59 volumio (udev-worker)[3712]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Mar 27 19:40:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 19:40:59 volumio sudo[3700]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:59 volumio volumio[1269]: info: Shairport-Sync Started Mar 27 19:40:59 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 27 19:40:59 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Mar 27 19:40:59 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 19:40:59 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 27 19:40:59 volumio sudo[3715]: pam_unix(sudo:session): session closed for user root Mar 27 19:40:59 volumio volumio[1269]: info: Shairport-Sync Started Mar 27 19:40:59 volumio systemd[1]: mpd.service: Deactivated successfully. Mar 27 19:40:59 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 27 19:40:59 volumio systemd[1]: mpd.service: Consumed 3.339s CPU time. Mar 27 19:40:59 volumio systemd[1]: mpd.socket: Deactivated successfully. Mar 27 19:40:59 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 27 19:40:59 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 27 19:40:59 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 27 19:40:59 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Mar 27 19:40:59 volumio volumio[1269]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 19:40:59 volumio sudo[3736]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 19:40:59 volumio sudo[3736]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 27 19:40:59 volumio sudo[3736]: pam_unix(sudo:session): session closed for user root Mar 27 19:41:01 volumio mpd[3738]: 2026-03-27T19:41:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 19:41:02 volumio systemd[1]: Started mpd.service - Music Player Daemon. Mar 27 19:41:02 volumio sudo[3671]: pam_unix(sudo:session): session closed for user root Mar 27 19:41:02 volumio sudo[3690]: pam_unix(sudo:session): session closed for user root Mar 27 19:41:02 volumio volumio[1269]: error: MPD error: The expression evaluated to a falsy value: Mar 27 19:41:02 volumio volumio[1269]: assert.ok(self.idling) Mar 27 19:41:02 volumio volumio[1269]: error: The expression evaluated to a falsy value: Mar 27 19:41:02 volumio volumio[1269]: assert.ok(self.idling) Mar 27 19:41:02 volumio volumio[1269]: error: updateQueue error: null Mar 27 19:41:03 volumio volumio[1269]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Mar 27 19:41:12 volumio volumio[1269]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Mar 27 19:41:12 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Mar 27 19:41:12 volumio volumio[1269]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Mar 27 19:41:13 volumio volumio[1269]: --2026-03-27 19:41:12-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Mar 27 19:41:13 volumio volumio[1269]: Resolving github.com (github.com)... 140.82.121.4 Mar 27 19:41:13 volumio volumio[1269]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Mar 27 19:41:13 volumio volumio[1269]: HTTP request sent, awaiting response... 302 Found Mar 27 19:41:13 volumio volumio[1269]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Mar 27 19:41:13 volumio volumio[1269]: --2026-03-27 19:41:13-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Mar 27 19:41:13 volumio volumio[1269]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.108.133, 185.199.109.133, ... Mar 27 19:41:13 volumio volumio[1269]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Mar 27 19:41:13 volumio volumio[1269]: HTTP request sent, awaiting response... 200 OK Mar 27 19:41:13 volumio volumio[1269]: Length: 362 [text/plain] Mar 27 19:41:13 volumio volumio[1269]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Mar 27 19:41:13 volumio volumio[1269]: 0K 100% 9.11M=0s Mar 27 19:41:13 volumio volumio[1269]: 2026-03-27 19:41:13 (9.11 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Mar 27 19:41:13 volumio volumio[1269]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 19:41:13 volumio volumio[1269]: info: CoreCommandRouter::Close All Modals sent Mar 27 19:41:13 volumio volumio[1269]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Mar 27 19:41:13 volumio volumio[1269]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Mar 27 19:41:16 volumio volumio5-onboarding[3631]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Mar 27 19:41:16 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Mar 27 19:41:16 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Mar 27 19:41:16 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 15. Mar 27 19:41:16 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:41:16 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Mar 27 19:41:16 volumio volumio5-onboarding[3777]: time=2026-03-27T19:41:16.586+03:00 level=INFO msg="running volumio5-device-gateway" version=10ca7e93+CHANGES buildDate=2026-03-06T14:30:20Z Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:13-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Mar 27 19:41:18 volumio volumio[1269]: Resolving github.com (github.com)... 140.82.121.4 Mar 27 19:41:18 volumio volumio[1269]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Mar 27 19:41:18 volumio volumio[1269]: HTTP request sent, awaiting response... 404 Not Found Mar 27 19:41:18 volumio volumio[1269]: 2026-03-27 19:41:14 ERROR 404: Not Found. Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:14-- http://a/ Mar 27 19:41:18 volumio volumio[1269]: Resolving a (a)... failed: Device or resource busy. Mar 27 19:41:18 volumio volumio[1269]: wget: unable to resolve host address ‘a’ Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:14-- http://packet.zip/ Mar 27 19:41:18 volumio volumio[1269]: Resolving packet.zip (packet.zip)... 162.255.119.167 Mar 27 19:41:18 volumio volumio[1269]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Mar 27 19:41:18 volumio volumio[1269]: HTTP request sent, awaiting response... 302 Found Mar 27 19:41:18 volumio volumio[1269]: Location: http://www.packet.zip/ [following] Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:15-- http://www.packet.zip/ Mar 27 19:41:18 volumio volumio[1269]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Mar 27 19:41:18 volumio volumio[1269]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 19:41:18 volumio volumio[1269]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. Mar 27 19:41:18 volumio volumio[1269]: Retrying. Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:16-- (try: 2) http://www.packet.zip/ Mar 27 19:41:18 volumio volumio[1269]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 19:41:18 volumio volumio[1269]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. Mar 27 19:41:18 volumio volumio[1269]: Retrying. Mar 27 19:41:18 volumio volumio[1269]: --2026-03-27 19:41:18-- (try: 3) http://www.packet.zip/ Mar 27 19:41:18 volumio volumio[1269]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Mar 27 19:41:18 volumio volumio[1269]: HTTP request sent, awaiting response... 403 Forbidden Mar 27 19:41:18 volumio volumio[1269]: 2026-03-27 19:41:18 ERROR 403: Forbidden. Mar 27 19:41:18 volumio volumio[1269]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove 'a': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 19:41:18 volumio volumio[1269]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove 'a': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Mar 27 19:41:18 volumio volumio[1269]: at checkExecSyncError (node:child_process:887:11) Mar 27 19:41:18 volumio volumio[1269]: at execSync (node:child_process:959:15) Mar 27 19:41:18 volumio volumio[1269]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Mar 27 19:41:18 volumio volumio[1269]: at new Promise () Mar 27 19:41:18 volumio volumio[1269]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Mar 27 19:41:18 volumio volumio[1269]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Mar 27 19:41:18 volumio volumio[1269]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Mar 27 19:41:18 volumio volumio[1269]: at Socket.emit (node:events:514:28) Mar 27 19:41:18 volumio volumio[1269]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 27 19:41:18 volumio volumio[1269]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Mar 27 19:41:18 volumio volumio[1269]: status: 1, Mar 27 19:41:18 volumio volumio[1269]: signal: null, Mar 27 19:41:18 volumio volumio[1269]: output: [ Mar 27 19:41:18 volumio volumio[1269]: null, Mar 27 19:41:18 volumio volumio[1269]: , Mar 27 19:41:18 volumio volumio[1269]: Mar 27 19:41:18 volumio volumio[1269]: ], Mar 27 19:41:18 volumio volumio[1269]: pid: 3786, Mar 27 19:41:18 volumio volumio[1269]: stdout: , Mar 27 19:41:18 volumio volumio[1269]: stderr: Mar 27 19:41:18 volumio volumio[1269]: } Mar 27 19:41:18 volumio volumio[1269]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 19:41:18 volumio sudo[3802]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 19:40' Mar 27 19:41:18 volumio sudo[3802]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"