Feb 22 18:06:04 volumio-hr volumio[1396]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Feb 22 18:06:04 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:04.976+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio[1396]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppyspectrum/volumio/bookworm/armhf Feb 22 18:06:06 volumio-hr volumio[1396]: info: Folder /tmp/plugins removed Feb 22 18:06:06 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:06.448+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:06.685+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio[1396]: info: Check plugin dependencies Feb 22 18:06:06 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 22 18:06:06 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:06.732+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio[1396]: info: Checking if plugin already exists Feb 22 18:06:06 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:06.736+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio[1396]: info: Rename folder Feb 22 18:06:06 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:06.760+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:06 volumio-hr volumio[1396]: info: Move to category Feb 22 18:06:06 volumio-hr volumio[1396]: info: Folder /tmp/downloaded_plugin.zip removed Feb 22 18:06:07 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:07.364+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:07 volumio-hr volumio[1396]: info: Checking if install.sh is present Feb 22 18:06:07 volumio-hr volumio[1396]: info: Executing install.sh Feb 22 18:06:07 volumio-hr sudo[5890]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppyspectrum/install.sh Feb 22 18:06:07 volumio-hr sudo[5890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:06:07 volumio-hr volumio[1396]: info: Installing peppyalsa plugin dependencies Feb 22 18:06:07 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:07.415+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:07 volumio-hr sudo[5894]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/usermod -aG render volumio Feb 22 18:06:07 volumio-hr sudo[5894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:07 volumio-hr sudo[5894]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:07 volumio-hr sudo[5899]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Feb 22 18:06:07 volumio-hr sudo[5899]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:07 volumio-hr volumio[1396]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Feb 22 18:06:07 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:07.990+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:08 volumio-hr volumio[1396]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Feb 22 18:06:08 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:08.231+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:12 volumio-hr volumio[1396]: info: Reading package lists... Feb 22 18:06:12 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:12.255+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:12 volumio-hr sudo[5899]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:12 volumio-hr sudo[6036]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Feb 22 18:06:12 volumio-hr sudo[6036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:13 volumio-hr sudo[6036]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:13 volumio-hr sudo[6039]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppyspectrum /data/INTERNAL/PeppySpectrum/Templates Feb 22 18:06:13 volumio-hr sudo[6039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:13 volumio-hr sudo[6039]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:13 volumio-hr volumio[1396]: info: installing apt packages Feb 22 18:06:13 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:13.887+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:13 volumio-hr sudo[6042]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install python3-pygame python3-pillow libfftw3-dev Feb 22 18:06:13 volumio-hr sudo[6042]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:16 volumio-hr volumio[1396]: info: Reading package lists... Feb 22 18:06:16 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:16.602+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:17 volumio-hr volumio[1396]: info: Building dependency tree... Feb 22 18:06:17 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:17.097+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:17 volumio-hr volumio[1396]: info: Reading state information... Feb 22 18:06:17 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:17.102+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: The following additional packages will be installed: Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.069+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-double3 libimagequant0 Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.079+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 libsdl2-mixer-2.0-0 Feb 22 18:06:18 volumio-hr volumio[1396]: info: libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy python3-olefile Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: Suggested packages: Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.091+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: bzip2-doc libfftw3-doc gcc gfortran python3-dev python3-pytest Feb 22 18:06:18 volumio-hr volumio[1396]: info: python-pil-doc python-pygame-doc timidity Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.094+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.094+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: The following NEW packages will be installed: Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.176+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: bzip2 file fonts-freefont-ttf libfftw3-bin libfftw3-dev libfftw3-double3 Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.189+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: libimagequant0 libopusfile0 libportmidi0 libraqm0 libsdl2-image-2.0-0 Feb 22 18:06:18 volumio-hr volumio[1396]: info: libsdl2-mixer-2.0-0 libsdl2-ttf-2.0-0 mailcap mime-support python3-numpy Feb 22 18:06:18 volumio-hr volumio[1396]: info: python3-olefile python3-pil python3-pygame Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.198+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.198+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.199+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: 0 upgraded, 19 newly installed, 0 to remove and 6 not upgraded. Feb 22 18:06:18 volumio-hr volumio[1396]: info: Need to get 9480 kB of archives. Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.639+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: After this operation, 45.6 MB of additional disk space will be used. Feb 22 18:06:18 volumio-hr volumio[1396]: info: Get:1 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf bzip2 armhf 1.0.8-5+b2 [47.4 kB] Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.643+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.643+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.643+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: Get:2 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf file armhf 1:5.44-3 [41.5 kB] Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.724+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:18 volumio-hr volumio[1396]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf mailcap all 3.70+nmu1 [32.0 kB] Feb 22 18:06:18 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:18.848+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:19 volumio-hr volumio[1396]: info: Get:4 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf mime-support all 3.66 [10.9 kB] Feb 22 18:06:19 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:19.002+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:19 volumio-hr volumio[1396]: info: Get:5 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf fonts-freefont-ttf all 20120503-10 [2552 kB] Feb 22 18:06:19 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:19.178+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:20 volumio-hr autossh[5669]: starting ssh (count 11) Feb 22 18:06:20 volumio-hr autossh[5669]: ssh child pid is 6063 Feb 22 18:06:21 volumio-hr volumio[1396]: info: Get:16 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-numpy armhf 1:1.24.2-1+deb12u1 [3827 kB] Feb 22 18:06:21 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:21.337+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:21 volumio-hr volumio[1396]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pil armhf 9.4.0-1.1+deb12u1 [433 kB] Feb 22 18:06:21 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:21.669+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:21 volumio-hr volumio[1396]: info: Get:6 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libfftw3-double3 armhf 3.3.10-1 [325 kB] Feb 22 18:06:21 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:21.881+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:22 volumio-hr volumio[1396]: info: Get:7 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libfftw3-bin armhf 3.3.10-1 [40.8 kB] Feb 22 18:06:22 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:22.293+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:22 volumio-hr volumio[1396]: info: Get:8 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libfftw3-dev armhf 3.3.10-1 [827 kB] Feb 22 18:06:22 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:22.359+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:9 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libimagequant0 armhf 2.17.0-1 [28.0 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.080+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:10 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libopusfile0 armhf 0.12-4 [38.2 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.114+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:11 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libportmidi0 armhf 1:217-6.1 [20.1 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.127+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:12 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf libraqm0 armhf 0.7.0-4.1 [9176 B] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.132+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:13 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-image-2.0-0 armhf 2.6.3+dfsg-1 [54.6 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.144+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:14 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-mixer-2.0-0 armhf 2.6.2+dfsg-2 [69.2 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.148+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:15 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl2-ttf-2.0-0 armhf 2.20.1+dfsg-2 [26.2 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.216+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:17 http://mirrors.ircam.fr/pub/raspbian/raspbian bookworm/main armhf python3-olefile all 0.46-3 [36.1 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.235+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:23 volumio-hr volumio[1396]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-pygame armhf 2.1.2+dfsg-5 [1061 kB] Feb 22 18:06:23 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:23.250+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:25 volumio-hr volumio[1396]: info: Fetched 9480 kB in 6s (1643 kB/s) Feb 22 18:06:25 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:25.356+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:25 volumio-hr volumio[1396]: info: Selecting previously unselected package bzip2. Feb 22 18:06:25 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:25.687+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:26 volumio-hr volumio[1396]: [620B blob data] Feb 22 18:06:26 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:26.966+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:26 volumio-hr volumio[1396]: info: Preparing to unpack .../00-bzip2_1.0.8-5+b2_armhf.deb ... Feb 22 18:06:26 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:26.969+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:27 volumio-hr volumio[1396]: info: Unpacking bzip2 (1.0.8-5+b2) ... Feb 22 18:06:27 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:27.084+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:28 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:06:28 volumio-hr volumio[1396]: info: Prefetching next song Feb 22 18:06:28 volumio-hr volumio[1396]: info: DOING PREFETCH IN MPD Feb 22 18:06:28 volumio-hr volumio[1396]: verbose: ControllerMpd::sendMpdCommand add "NAS/Musica/Acoustic Alchemy - Discography 1987-2018 FLAC/2002 - The Very Best Of/03 - Ariane.flac" Feb 22 18:06:28 volumio-hr volumio[1396]: info: Feb 22 18:06:28 volumio-hr volumio[1396]: ---------------------------- MPD announces system playlist update Feb 22 18:06:28 volumio-hr volumio[1396]: info: Ignoring MPD Status Update Feb 22 18:06:28 volumio-hr volumio[1396]: info: sendMpdCommand add "NAS/Musica/Acoustic Alchemy - Discography 1987-2018 FLAC/2002 - The Very Best Of/03 - Ariane.flac" took 1 milliseconds Feb 22 18:06:28 volumio-hr volumio[1396]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 22 18:06:28 volumio-hr volumio[1396]: info: ------------------------------ 2ms Feb 22 18:06:28 volumio-hr volumio[1396]: info: sendMpdCommand consume 1 took 1 milliseconds Feb 22 18:06:28 volumio-hr volumio[1396]: info: Selecting previously unselected package file. Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.377+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Preparing to unpack .../01-file_1%3a5.44-3_armhf.deb ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.383+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Unpacking file (1:5.44-3) ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.394+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Selecting previously unselected package mailcap. Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.521+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Preparing to unpack .../02-mailcap_3.70+nmu1_all.deb ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.532+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Unpacking mailcap (3.70+nmu1) ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.548+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Selecting previously unselected package mime-support. Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.717+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Preparing to unpack .../03-mime-support_3.66_all.deb ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.724+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Unpacking mime-support (3.66) ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.731+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Selecting previously unselected package fonts-freefont-ttf. Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.830+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Preparing to unpack .../04-fonts-freefont-ttf_20120503-10_all.deb ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.843+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:28 volumio-hr volumio[1396]: info: Unpacking fonts-freefont-ttf (20120503-10) ... Feb 22 18:06:28 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:28.853+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:29 volumio-hr volumio[1396]: info: Selecting previously unselected package libfftw3-double3:armhf. Feb 22 18:06:29 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:29.727+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:29 volumio-hr volumio[1396]: info: Preparing to unpack .../05-libfftw3-double3_3.3.10-1_armhf.deb ... Feb 22 18:06:29 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:29.745+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:29 volumio-hr volumio[1396]: info: Unpacking libfftw3-double3:armhf (3.3.10-1) ... Feb 22 18:06:29 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:29.757+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:29 volumio-hr volumio[1396]: info: Selecting previously unselected package libfftw3-bin. Feb 22 18:06:29 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:29.998+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Preparing to unpack .../06-libfftw3-bin_3.3.10-1_armhf.deb ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.003+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Unpacking libfftw3-bin (3.3.10-1) ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.015+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Selecting previously unselected package libfftw3-dev:armhf. Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.158+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Preparing to unpack .../07-libfftw3-dev_3.3.10-1_armhf.deb ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.167+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Unpacking libfftw3-dev:armhf (3.3.10-1) ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.180+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Selecting previously unselected package libimagequant0:armhf. Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.657+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Preparing to unpack .../08-libimagequant0_2.17.0-1_armhf.deb ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.666+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Unpacking libimagequant0:armhf (2.17.0-1) ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.674+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Selecting previously unselected package libopusfile0:armhf. Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.814+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Preparing to unpack .../09-libopusfile0_0.12-4_armhf.deb ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.822+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Unpacking libopusfile0:armhf (0.12-4) ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.831+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Selecting previously unselected package libportmidi0:armhf. Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.948+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Preparing to unpack .../10-libportmidi0_1%3a217-6.1_armhf.deb ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.954+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:30 volumio-hr volumio[1396]: info: Unpacking libportmidi0:armhf (1:217-6.1) ... Feb 22 18:06:30 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:30.983+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Selecting previously unselected package libraqm0:armhf. Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.095+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Preparing to unpack .../11-libraqm0_0.7.0-4.1_armhf.deb ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.108+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Unpacking libraqm0:armhf (0.7.0-4.1) ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.118+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Selecting previously unselected package libsdl2-image-2.0-0:armhf. Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.230+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Preparing to unpack .../12-libsdl2-image-2.0-0_2.6.3+dfsg-1_armhf.deb ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.243+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Unpacking libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.250+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Selecting previously unselected package libsdl2-mixer-2.0-0:armhf. Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.374+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Preparing to unpack .../13-libsdl2-mixer-2.0-0_2.6.2+dfsg-2_armhf.deb ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.384+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Unpacking libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.395+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Selecting previously unselected package libsdl2-ttf-2.0-0:armhf. Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.544+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Preparing to unpack .../14-libsdl2-ttf-2.0-0_2.20.1+dfsg-2_armhf.deb ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.553+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Unpacking libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.582+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Selecting previously unselected package python3-numpy. Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.766+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Preparing to unpack .../15-python3-numpy_1%3a1.24.2-1+deb12u1_armhf.deb ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.777+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:31 volumio-hr volumio[1396]: info: Unpacking python3-numpy (1:1.24.2-1+deb12u1) ... Feb 22 18:06:31 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:31.797+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:32 volumio-hr volumio[1396]: info: Feb 22 18:06:32 volumio-hr volumio[1396]: ---------------------------- MPD announces system playlist update Feb 22 18:06:32 volumio-hr volumio[1396]: info: Ignoring MPD Status Update Feb 22 18:06:32 volumio-hr volumio[1396]: info: Feb 22 18:06:32 volumio-hr volumio[1396]: ---------------------------- MPD announces state update: player Feb 22 18:06:32 volumio-hr volumio[1396]: info: ControllerMpd::getState Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: ControllerMpd::sendMpdCommand status Feb 22 18:06:32 volumio-hr volumio[1396]: info: ------------------------------ 1ms Feb 22 18:06:32 volumio-hr volumio[1396]: info: sendMpdCommand status took 2 milliseconds Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: ControllerMpd::parseState Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 22 18:06:32 volumio-hr volumio[1396]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: ControllerMpd::parseTrackInfo Feb 22 18:06:32 volumio-hr volumio[1396]: info: ControllerMpd::pushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::servicePushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":294,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"703 Kbps","isStreaming":false,"title":"Ariane","artist":"Acoustic Alchemy","album":"The Very Best of Acoustic Alchemy","uri":"NAS/Musica/Acoustic Alchemy - Discography 1987-2018 FLAC/2002 - The Very Best Of/03 - Ariane.flac","trackType":"flac"} Feb 22 18:06:32 volumio-hr volumio[1396]: verbose: CURRENT POSITION 1 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::syncState stateService play Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::syncState currentStatus play Feb 22 18:06:32 volumio-hr volumio[1396]: info: Received an update from plugin. extracting info from payload Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::pushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioPushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output update for this device Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::pushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioPushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output update for this device Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 1 Feb 22 18:06:32 volumio-hr volumio[1396]: info: ------------------------------ 17ms Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::startPlaybackTimer Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreStateMachine::pushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioPushState Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output update for this device Feb 22 18:06:32 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output Feb 22 18:06:32 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:06:32 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:06:39 volumio-hr volumio[1396]: info: Selecting previously unselected package python3-olefile. Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.358+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:39 volumio-hr volumio[1396]: info: Preparing to unpack .../16-python3-olefile_0.46-3_all.deb ... Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.371+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:39 volumio-hr volumio[1396]: info: Unpacking python3-olefile (0.46-3) ... Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.378+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:39 volumio-hr volumio[1396]: info: Selecting previously unselected package python3-pil:armhf. Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.522+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:39 volumio-hr volumio[1396]: info: Preparing to unpack .../17-python3-pil_9.4.0-1.1+deb12u1_armhf.deb ... Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.525+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:39 volumio-hr volumio[1396]: info: Unpacking python3-pil:armhf (9.4.0-1.1+deb12u1) ... Feb 22 18:06:39 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:39.535+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:40 volumio-hr volumio[1396]: info: Selecting previously unselected package python3-pygame. Feb 22 18:06:40 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:40.237+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:40 volumio-hr volumio[1396]: info: Preparing to unpack .../18-python3-pygame_2.1.2+dfsg-5_armhf.deb ... Feb 22 18:06:40 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:40.248+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:40 volumio-hr volumio[1396]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ... Feb 22 18:06:40 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:40.256+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.057+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up python3-olefile (0.46-3) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.138+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumiossh-tunnel[6063]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up fonts-freefont-ttf (20120503-10) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.794+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up file (1:5.44-3) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.815+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up bzip2 (1.0.8-5+b2) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.841+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up libraqm0:armhf (0.7.0-4.1) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.869+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up libportmidi0:armhf (1:217-6.1) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.898+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up libimagequant0:armhf (2.17.0-1) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.947+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:42 volumio-hr volumio[1396]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ... Feb 22 18:06:42 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:42.980+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:48 volumio-hr volumio[1396]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ... Feb 22 18:06:48 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:48.957+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.008+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up mailcap (3.70+nmu1) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.028+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up libopusfile0:armhf (0.12-4) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.200+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up mime-support (3.66) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.228+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up libfftw3-bin (3.3.10-1) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.248+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:49 volumio-hr volumio[1396]: info: Setting up python3-pil:armhf (9.4.0-1.1+deb12u1) ... Feb 22 18:06:49 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:49.276+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:50 volumio-hr volumio[1396]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ... Feb 22 18:06:50 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:50.377+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:50 volumio-hr volumio[1396]: info: Setting up python3-pygame (2.1.2+dfsg-5) ... Feb 22 18:06:50 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:50.398+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:50 volumio-hr volumio[1396]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ... Feb 22 18:06:50 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:50.981+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:51 volumio-hr volumio[1396]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Feb 22 18:06:51 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:51.050+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:51 volumio-hr volumio[1396]: info: Processing triggers for man-db (2.11.2-2) ... Feb 22 18:06:51 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:51.196+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:51 volumio-hr volumio[1396]: info: Processing triggers for fontconfig (2.14.1-4) ... Feb 22 18:06:51 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:51.505+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:52 volumio-hr sudo[6042]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:52 volumio-hr volumio[1396]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so Feb 22 18:06:52 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:52.965+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:52 volumio-hr volumio[1396]: info: Linked /data/plugins/user_interface/peppyspectrum/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so.0 Feb 22 18:06:52 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:52.967+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:52 volumio-hr sudo[6388]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppyspectrum/startpeppyspectrum.sh Feb 22 18:06:52 volumio-hr sudo[6388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:52 volumio-hr sudo[6388]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:53 volumio-hr sudo[6390]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Feb 22 18:06:53 volumio-hr sudo[6390]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:06:53 volumio-hr systemd[1]: Reloading. Feb 22 18:06:58 volumio-hr systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 18:06:58 volumio-hr systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 22 18:06:58 volumio-hr sudo[6390]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:58 volumio-hr sudo[5890]: pam_unix(sudo:session): session closed for user root Feb 22 18:06:58 volumio-hr volumio[1396]: info: Install script completed Feb 22 18:06:58 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:58.972+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:58 volumio-hr volumio[1396]: info: Adding reference to registry Feb 22 18:06:58 volumio-hr volumio5-onboarding[1606]: time=2026-02-22T18:06:58.978+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 22 18:06:58 volumio-hr volumio[1396]: info: Done installing plugin. Feb 22 18:06:58 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 22 18:06:58 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 22 18:06:58 volumio-hr volumio[1396]: Plugin install end detected on script Feb 22 18:06:58 volumio-hr volumio[1396]: info: Folder /tmp/plugins removed Feb 22 18:06:58 volumio-hr volumio[1396]: info: Folder /tmp/downloaded_plugin.zip removed Feb 22 18:06:58 volumio-hr volumio[1396]: info: Folder /data/temp removed Feb 22 18:07:06 volumio-hr volumio[1396]: info: Enabling plugin peppyspectrum Feb 22 18:07:06 volumio-hr volumio[1396]: info: Loading plugin "peppyspectrum"... Feb 22 18:07:07 volumio-hr volumio[1396]: info: Preparing to generate the ALSA configuration file Feb 22 18:07:07 volumio-hr volumio[1396]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 18:07:07 volumio-hr volumio[1396]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 22 18:07:07 volumio-hr volumio[1396]: info: Reading ALSA contributions from plugins. Feb 22 18:07:07 volumio-hr volumio[1396]: info: Asound.conf file written Feb 22 18:07:07 volumio-hr sudo[6444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 18:07:07 volumio-hr sudo[6444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr sudo[6444]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 18:07:07 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 18:07:07 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 18:07:07 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:07 volumio-hr volumio[1396]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Feb 22 18:07:07 volumio-hr volumio[1396]: Hardware is initialized using a generic method Feb 22 18:07:07 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:07 volumio-hr volumio[1396]: info: Output device has changed, restarting MPD Feb 22 18:07:07 volumio-hr volumio[1396]: info: Output device has changed, restarting Shairport Sync Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:07 volumio-hr sudo[6452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 18:07:07 volumio-hr sudo[6452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr sudo[6452]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr sudo[6456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 18:07:07 volumio-hr sudo[6456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 18:07:07 volumio-hr volumio[1396]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: QobuzConnect: setDeactiveState invoked Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:07 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:07 volumio-hr systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 22 18:07:07 volumio-hr volumio[1396]: info: PLUGIN START: peppyspectrum Feb 22 18:07:07 volumio-hr vtcs[3639]: [2026-02-22 18:07:07.493] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Feb 22 18:07:07 volumio-hr vtcs[3639]: [2026-02-22 18:07:07.493] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Feb 22 18:07:07 volumio-hr sudo[6464]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:07 volumio-hr sudo[6464]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 22 18:07:07 volumio-hr volumio[1396]: info: Preparing to generate the ALSA configuration file Feb 22 18:07:07 volumio-hr volumio[1396]: info: Done. Feb 22 18:07:07 volumio-hr sudo[6467]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-aloop index=7 pcm_substreams=1 Feb 22 18:07:07 volumio-hr sudo[6467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Feb 22 18:07:07 volumio-hr systemd[1]: vtcs.service: Deactivated successfully. Feb 22 18:07:07 volumio-hr systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Feb 22 18:07:07 volumio-hr sudo[6471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:07 volumio-hr sudo[6471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr sudo[6464]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr volumio[1396]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 18:07:07 volumio-hr volumio[1396]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 22 18:07:07 volumio-hr volumio[1396]: info: Reading ALSA contributions from plugins. Feb 22 18:07:07 volumio-hr volumio[1396]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Feb 22 18:07:07 volumio-hr sudo[6478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 18:07:07 volumio-hr volumio[1396]: info: MPD Permissions set Feb 22 18:07:07 volumio-hr sudo[6478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Feb 22 18:07:07 volumio-hr volumio[1396]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 18:07:07 volumio-hr systemd[1]: mpd.service: Consumed 9.286s CPU time. Feb 22 18:07:07 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Feb 22 18:07:07 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 18:07:07 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr sudo[6467]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 18:07:07 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 18:07:07 volumio-hr volumio[1396]: info: snd_dummy loaded Feb 22 18:07:07 volumio-hr sudo[6478]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr (udev-worker)[6495]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Feb 22 18:07:07 volumio-hr sudo[6471]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr sudo[6498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 18:07:07 volumio-hr sudo[6498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr volumio[1396]: info: Starting Shairport Sync Feb 22 18:07:07 volumio-hr systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Feb 22 18:07:07 volumio-hr volumio[1396]: info: Asound.conf file unchanged, so no further update is needed Feb 22 18:07:07 volumio-hr volumio[1396]: info: Output device has changed, restarting MPD Feb 22 18:07:07 volumio-hr sudo[6502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 18:07:07 volumio-hr sudo[6502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr volumio[1396]: info: Output device has changed, restarting Shairport Sync Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:07 volumio-hr qobuz-connect[3619]: 20260222 18:07:07.931 [3619.3619] INFO SampleApp: Stopping Local configuration server Feb 22 18:07:07 volumio-hr sudo[6505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 18:07:07 volumio-hr sudo[6492]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 18:07:07 volumio-hr sudo[6492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:07:07 volumio-hr sudo[6505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr sudo[6505]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 18:07:07 volumio-hr sudo[6507]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 18:07:07 volumio-hr sudo[6507]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:07 volumio-hr sudo[6492]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:07 volumio-hr volumio[1396]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 18:07:07 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:07 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 18:07:07 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:07 volumio-hr systemd[1]: shairport-sync.service: Consumed 7.498s CPU time. Feb 22 18:07:07 volumio-hr volumio[1396]: info: QobuzConnect: setDeactiveState invoked Feb 22 18:07:07 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:07 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:07 volumio-hr volumio[1396]: info: Preparing to generate the ALSA configuration file Feb 22 18:07:07 volumio-hr sudo[6519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:07 volumio-hr sudo[6519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:08 volumio-hr sudo[6502]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Feb 22 18:07:08 volumio-hr sudo[6525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:08 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 18:07:08 volumio-hr sudo[6525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 18:07:08 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 18:07:08 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 18:07:08 volumio-hr volumio[1396]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 18:07:08 volumio-hr volumio[1396]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 22 18:07:08 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 18:07:08 volumio-hr volumio[1396]: info: Reading ALSA contributions from plugins. Feb 22 18:07:08 volumio-hr volumio[1396]: info: MPD Permissions set Feb 22 18:07:08 volumio-hr volumio[1396]: info: Shairport-Sync Started Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr sudo[6534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 18:07:08 volumio-hr sudo[6534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6525]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr sudo[6519]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: Starting Shairport Sync Feb 22 18:07:08 volumio-hr sudo[6534]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr sudo[6560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 18:07:08 volumio-hr sudo[6560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6559]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 18:07:08 volumio-hr sudo[6559]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6535]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 18:07:08 volumio-hr sudo[6535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:07:08 volumio-hr sudo[6535]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: Asound.conf file written Feb 22 18:07:08 volumio-hr sudo[6567]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 18:07:08 volumio-hr sudo[6567]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6567]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 18:07:08 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:08 volumio-hr volumio[1396]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Feb 22 18:07:08 volumio-hr volumio[1396]: Hardware is initialized using a generic method Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card Loopback Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Feb 22 18:07:08 volumio-hr volumio[1396]: Hardware is initialized using a generic method Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card Loopback Feb 22 18:07:08 volumio-hr volumio[1396]: info: Output device has changed, restarting MPD Feb 22 18:07:08 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:08 volumio-hr sudo[6560]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: Output device has changed, restarting Shairport Sync Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:08 volumio-hr sudo[6577]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 18:07:08 volumio-hr sudo[6577]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6576]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 18:07:08 volumio-hr sudo[6576]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6576]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 18:07:08 volumio-hr volumio[1396]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: QobuzConnect: setDeactiveState invoked Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:08 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:08 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 18:07:08 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 18:07:08 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 18:07:08 volumio-hr volumio[1396]: info: Preparing to generate the ALSA configuration file Feb 22 18:07:08 volumio-hr sudo[6602]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:08 volumio-hr sudo[6602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:08 volumio-hr sudo[6605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr volumio[1396]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 22 18:07:08 volumio-hr volumio[1396]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 22 18:07:08 volumio-hr volumio[1396]: info: Reading ALSA contributions from plugins. Feb 22 18:07:08 volumio-hr volumio[1396]: info: MPD Permissions set Feb 22 18:07:08 volumio-hr volumio[1396]: info: Shairport-Sync Started Feb 22 18:07:08 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 18:07:08 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 18:07:08 volumio-hr sudo[6613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 18:07:08 volumio-hr sudo[6613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr volumio[1396]: (node:1396) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 18:07:08 volumio-hr volumio[1396]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 22 18:07:08 volumio-hr volumio[1396]: (node:1396) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 18:07:08 volumio-hr volumio[1396]: (node:1396) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 18:07:08 volumio-hr volumio[1396]: (node:1396) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 18:07:08 volumio-hr volumio[1396]: (node:1396) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: Starting Shairport Sync Feb 22 18:07:08 volumio-hr sudo[6613]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr sudo[6605]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr sudo[6622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 18:07:08 volumio-hr sudo[6622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6602]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: Asound.conf file written Feb 22 18:07:08 volumio-hr sudo[6623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 18:07:08 volumio-hr sudo[6614]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 18:07:08 volumio-hr sudo[6614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:07:08 volumio-hr sudo[6623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6614]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr sudo[6630]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 22 18:07:08 volumio-hr sudo[6630]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6630]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:08 volumio-hr volumio[1396]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:8750" "" "" Feb 22 18:07:08 volumio-hr volumio[1396]: Hardware is initialized using a generic method Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card E30 Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card Loopback Feb 22 18:07:08 volumio-hr volumio[1396]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 22 18:07:08 volumio-hr volumio[1396]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Feb 22 18:07:08 volumio-hr volumio[1396]: Hardware is initialized using a generic method Feb 22 18:07:08 volumio-hr volumio[1396]: No state is present for card Loopback Feb 22 18:07:08 volumio-hr volumio[1396]: info: Output device has changed, restarting MPD Feb 22 18:07:08 volumio-hr volumio[1396]: info: Output device has changed, restarting Shairport Sync Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:08 volumio-hr sudo[6638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 22 18:07:08 volumio-hr sudo[6638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr sudo[6638]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 18:07:08 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:08 volumio-hr sudo[6643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 22 18:07:08 volumio-hr sudo[6643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:08 volumio-hr volumio[1396]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 22 18:07:08 volumio-hr volumio[1396]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:08 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:08 volumio-hr sudo[6623]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:08 volumio-hr volumio[1396]: info: QobuzConnect: setDeactiveState invoked Feb 22 18:07:08 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:08 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:08 volumio-hr systemd[1]: mpd.service: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 22 18:07:08 volumio-hr systemd[1]: mpd.socket: Deactivated successfully. Feb 22 18:07:08 volumio-hr systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 22 18:07:08 volumio-hr systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 22 18:07:08 volumio-hr sudo[6651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:08 volumio-hr sudo[6651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:09 volumio-hr sudo[6668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 22 18:07:09 volumio-hr sudo[6668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:09 volumio-hr systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 22 18:07:09 volumio-hr volumio[1396]: info: MPD Permissions set Feb 22 18:07:09 volumio-hr volumio[1396]: info: Shairport-Sync Started Feb 22 18:07:09 volumio-hr systemd[1]: Starting mpd.service - Music Player Daemon... Feb 22 18:07:09 volumio-hr sudo[6675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Feb 22 18:07:09 volumio-hr sudo[6675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 22 18:07:09 volumio-hr volumio[1396]: info: Starting Shairport Sync Feb 22 18:07:09 volumio-hr sudo[6651]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 22 18:07:09 volumio-hr sudo[6685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:09 volumio-hr sudo[6675]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6668]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Feb 22 18:07:09 volumio-hr sudo[6687]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:09 volumio-hr sudo[6677]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 22 18:07:09 volumio-hr systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 22 18:07:09 volumio-hr sudo[6677]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 22 18:07:09 volumio-hr systemd[1]: shairport-sync.service: Deactivated successfully. Feb 22 18:07:09 volumio-hr systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:09 volumio-hr sudo[6677]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 22 18:07:09 volumio-hr sudo[6685]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr volumio[1396]: info: Shairport-Sync Started Feb 22 18:07:09 volumio-hr qobuz-connect[3619]: 20260222 18:07:09.784 [3619.3619] INFO SampleApp: shat down connection on UNIX socket Feb 22 18:07:09 volumio-hr volumio[1396]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Feb 22 18:07:09 volumio-hr volumio[1396]: info: QobuzConnect: setDeactiveState invoked Feb 22 18:07:09 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:09 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:09 volumio-hr systemd[1]: qobuz-connect.service: Deactivated successfully. Feb 22 18:07:09 volumio-hr systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 18:07:09 volumio-hr systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Feb 22 18:07:09 volumio-hr sudo[6687]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6622]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6559]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:09 volumio-hr sudo[6498]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:10 volumio-hr volumio[1396]: info: Executing endpoint qc_getconfig Feb 22 18:07:10 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.053 [6708.6708] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Feb 22 18:07:10 volumio-hr volumio[1396]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Feb 22 18:07:10 volumio-hr volumio[1396]: info: QobuzConnect: QOBUZ Connect daemon connected Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.069 [6708.6708] INFO VolumeManager: [0x1eb3348]: Setting new playback volume: 75 Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.075 [6708.6708] INFO VolumeManager: [0x1eb3348]: Setting new mute state: 0 Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.075 [6708.6708] INFO QobuzConnect: [0x1eb3d18]: Client initialized! Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.075 [6708.6708] INFO SampleApp: Starting Avahi advertising, name: Volumio HR, service name: _qobuz-connect._tcp Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.086 [6708.6708] INFO LocalConfigManager: [0x1eb2c28]: Starting Local Configuration server Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.086 [6708.6708] INFO SampleApp: Starting Local configuration server Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.087 [6708.6708] INFO SampleApp: Connected to UNIX socket client 0x1e9d818 Feb 22 18:07:10 volumio-hr qobuz-connect[6708]: 20260222 18:07:10.205 [6708.6708] INFO SampleApp: Playback volume changed: 75 Feb 22 18:07:10 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:10 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:10 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:10 volumio-hr mpd[6693]: 2026-02-22T18:07:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 22 18:07:10 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:10 volumio-hr volumio[1396]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:10 volumio-hr volumio[1396]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:10 volumio-hr sudo[6723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 18:07:10 volumio-hr sudo[6723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:10 volumio-hr systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Feb 22 18:07:11 volumio-hr systemd[1]: Started mpd.service - Music Player Daemon. Feb 22 18:07:11 volumio-hr sudo[6577]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr sudo[6456]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr sudo[6507]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr sudo[6643]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr sudo[6723]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr volumio[1396]: error: MPD error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: MPD error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: MPD error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: The expression evaluated to a falsy value: Feb 22 18:07:11 volumio-hr volumio[1396]: assert.ok(self.idling) Feb 22 18:07:11 volumio-hr volumio[1396]: error: updateQueue error: null Feb 22 18:07:11 volumio-hr volumio[1396]: info: Executing endpoint tc_getconfig Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 22 18:07:11 volumio-hr vtcs[6726]: STARTING TidalConnect services, version: 1.6.1 Feb 22 18:07:11 volumio-hr vtcs[6726]: STARTED TidalConnect services. Feb 22 18:07:11 volumio-hr volumio[1396]: info: Executing endpoint tc_connect Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: Connecting to TidalConnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::servicePushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreStateMachine::pushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioPushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output update for this device Feb 22 18:07:11 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::servicePushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreStateMachine::pushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioPushState Feb 22 18:07:11 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output update for this device Feb 22 18:07:11 volumio-hr volumio[1396]: info: MRS: Pushing multiroomSync output Feb 22 18:07:11 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:11 volumio-hr volumio[1396]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: PeppySpectrum ---peppyspectrum status play Feb 22 18:07:11 volumio-hr volumio[1396]: info: PeppySpectrum ---peppyspectrum status play Feb 22 18:07:11 volumio-hr sudo[6741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 22 18:07:11 volumio-hr sudo[6741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:11 volumio-hr sudo[6747]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Feb 22 18:07:11 volumio-hr sudo[6747]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:11 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:11 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:11 volumio-hr volumio[1396]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:11 volumio-hr systemd[1]: Started peppyspectrum.service - peppyspectrum Daemon. Feb 22 18:07:11 volumio-hr sudo[6741]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr sudo[6747]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr volumio[1396]: info: peppyspectrum Daemon Started Feb 22 18:07:11 volumio-hr volumio[1396]: info: peppyspectrum Daemon Started Feb 22 18:07:11 volumio-hr sudo[6761]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 18:07:11 volumio-hr sudo[6761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:11 volumio-hr sudo[6761]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:11 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:11 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:11 volumio-hr volumio[1396]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:11 volumio-hr volumio[1396]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:11 volumio-hr sudo[6774]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 18:07:11 volumio-hr sudo[6774]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:11 volumio-hr sudo[6774]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:12 volumio-hr systemd[1]: peppyspectrum.service: Deactivated successfully. Feb 22 18:07:12 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:12 volumio-hr volumio[1396]: info: TidalConnect service stoped! Feb 22 18:07:12 volumio-hr volumio[1396]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:12 volumio-hr volumio[1396]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 22 18:07:12 volumio-hr sudo[6786]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 22 18:07:12 volumio-hr sudo[6786]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:12 volumio-hr sudo[6786]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:12 volumio-hr volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 18:07:14 volumio-hr volumio[1396]: info: TidalConnect service started! Feb 22 18:07:14 volumio-hr volumio[1396]: info: TidalConnect service started! Feb 22 18:07:14 volumio-hr volumio[1396]: info: TidalConnect service started! Feb 22 18:07:15 volumio-hr volumio[1396]: info: TidalConnect service started! Feb 22 18:07:18 volumio-hr volumio[1396]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Feb 22 18:07:18 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Feb 22 18:07:18 volumio-hr volumio[1396]: aaaaaaaaaaa 800,480 Feb 22 18:07:18 volumio-hr volumio[1396]: info: PeppySpectrum ---800480 Feb 22 18:07:18 volumio-hr volumio[1396]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 22 18:07:18 volumio-hr volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 18:07:18 volumio-hr volumio[1396]: info: CoreCommandRouter::Close All Modals sent Feb 22 18:07:18 volumio-hr volumio[1396]: info: Tunnel connection is inactive, restarting it Feb 22 18:07:18 volumio-hr volumio[1396]: info: Starting Tunnel 1 Feb 22 18:07:18 volumio-hr volumio[1396]: info: Starting Tunnel Connection Checker Feb 22 18:07:18 volumio-hr sudo[6811]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Feb 22 18:07:18 volumio-hr sudo[6811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:18 volumio-hr autossh[5669]: received signal to exit (15) Feb 22 18:07:18 volumio-hr systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Feb 22 18:07:18 volumio-hr systemd[1]: sshtunnel.service: Deactivated successfully. Feb 22 18:07:18 volumio-hr systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Feb 22 18:07:18 volumio-hr systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Feb 22 18:07:18 volumio-hr sudo[6811]: pam_unix(sudo:session): session closed for user root Feb 22 18:07:18 volumio-hr volumio[1396]: info: Remote SSH Started Feb 22 18:07:18 volumio-hr autossh[6814]: port set to 0, monitoring disabled Feb 22 18:07:18 volumio-hr autossh[6814]: starting ssh (count 1) Feb 22 18:07:18 volumio-hr autossh[6814]: ssh child pid is 6817 Feb 22 18:07:18 volumio-hr volumio[1396]: 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 22 18:07:18 volumio-hr volumio[1396]: info: CoreCommandRouter::volumioGetState Feb 22 18:07:18 volumio-hr volumio[1396]: info: CorePlayQueue::getTrack 2 Feb 22 18:07:19 volumio-hr volumiossh-tunnel[6817]: Warning: Permanently added '[eu10.myvolumio.org]:2222' (RSA) to the list of known hosts. Feb 22 18:07:22 volumio-hr volumio[1396]: info: CALLMETHOD: user_interface peppyspectrum savepeppy [object Object] Feb 22 18:07:22 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , savepeppy Feb 22 18:07:22 volumio-hr volumio[1396]: aaaaaaaaaaa 800,480 Feb 22 18:07:22 volumio-hr volumio[1396]: info: PeppySpectrum ---800480 Feb 22 18:07:22 volumio-hr volumio[1396]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 22 18:07:22 volumio-hr volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 18:07:22 volumio-hr volumio[1396]: info: CoreCommandRouter::Close All Modals sent Feb 22 18:07:32 volumio-hr volumio[1396]: info: CALLMETHOD: user_interface peppyspectrum updatelist Feb 22 18:07:32 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , updatelist Feb 22 18:07:32 volumio-hr volumio[1396]: info: PeppySpectrum --- wget 'https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt' Feb 22 18:07:33 volumio-hr volumio[1396]: --2026-02-22 18:07:32-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/spectrumslist.txt Feb 22 18:07:33 volumio-hr volumio[1396]: Resolving github.com (github.com)... 140.82.121.4 Feb 22 18:07:33 volumio-hr volumio[1396]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Feb 22 18:07:33 volumio-hr volumio[1396]: HTTP request sent, awaiting response... 302 Found Feb 22 18:07:33 volumio-hr volumio[1396]: Location: https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt [following] Feb 22 18:07:33 volumio-hr volumio[1396]: --2026-02-22 18:07:32-- https://raw.githubusercontent.com/balbuze/Spectrum-peppyspectrum/main/spectrumslist.txt Feb 22 18:07:33 volumio-hr volumio[1396]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.109.133, 185.199.110.133, ... Feb 22 18:07:33 volumio-hr volumio[1396]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Feb 22 18:07:33 volumio-hr volumio[1396]: HTTP request sent, awaiting response... 200 OK Feb 22 18:07:33 volumio-hr volumio[1396]: Length: 471 [text/plain] Feb 22 18:07:33 volumio-hr volumio[1396]: Saving to: ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ Feb 22 18:07:33 volumio-hr volumio[1396]: 0K 100% 4.81M=0s Feb 22 18:07:33 volumio-hr volumio[1396]: 2026-02-22 18:07:33 (4.81 MB/s) - ‘/data/plugins/user_interface/peppyspectrum/spectrumslist.txt’ saved [471/471] Feb 22 18:07:33 volumio-hr volumio[1396]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 22 18:07:33 volumio-hr volumio[1396]: info: CoreCommandRouter::Close All Modals sent Feb 22 18:07:35 volumio-hr volumio[1396]: info: CALLMETHOD: user_interface peppyspectrum dlspectrum [object Object] Feb 22 18:07:35 volumio-hr volumio[1396]: info: CoreCommandRouter::executeOnPlugin: peppyspectrum , dlspectrum Feb 22 18:07:36 volumio-hr volumio[1396]: --2026-02-22 18:07:35-- https://github.com/balbuze/Spectrum-peppyspectrum/raw/main/Zipped-folders/Choose Feb 22 18:07:36 volumio-hr volumio[1396]: Resolving github.com (github.com)... 140.82.121.4 Feb 22 18:07:36 volumio-hr volumio[1396]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Feb 22 18:07:36 volumio-hr volumio[1396]: HTTP request sent, awaiting response... 404 Not Found Feb 22 18:07:36 volumio-hr volumio[1396]: 2026-02-22 18:07:36 ERROR 404: Not Found. Feb 22 18:07:36 volumio-hr volumio[1396]: --2026-02-22 18:07:36-- http://a/ Feb 22 18:07:36 volumio-hr volumio[1396]: Resolving a (a)... failed: Device or resource busy. Feb 22 18:07:36 volumio-hr volumio[1396]: wget: unable to resolve host address ‘a’ Feb 22 18:07:36 volumio-hr volumio[1396]: --2026-02-22 18:07:36-- http://packet.zip/ Feb 22 18:07:36 volumio-hr volumio[1396]: Resolving packet.zip (packet.zip)... 162.255.119.167 Feb 22 18:07:36 volumio-hr volumio[1396]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Feb 22 18:07:36 volumio-hr volumio[1396]: HTTP request sent, awaiting response... 302 Found Feb 22 18:07:36 volumio-hr volumio[1396]: Location: http://www.packet.zip/ [following] Feb 22 18:07:36 volumio-hr volumio[1396]: --2026-02-22 18:07:36-- http://www.packet.zip/ Feb 22 18:07:36 volumio-hr volumio[1396]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Feb 22 18:07:36 volumio-hr volumio[1396]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Feb 22 18:07:36 volumio-hr volumio[1396]: HTTP request sent, awaiting response... 403 Forbidden Feb 22 18:07:36 volumio-hr volumio[1396]: 2026-02-22 18:07:36 ERROR 403: Forbidden. Feb 22 18:07:36 volumio-hr volumio[1396]: error: PeppySpectrum --- An error occurs while downloading or installing Spectrums Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove 'a': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 18:07:37 volumio-hr volumio[1396]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove 'a': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 22 18:07:37 volumio-hr volumio[1396]: at checkExecSyncError (node:child_process:887:11) Feb 22 18:07:37 volumio-hr volumio[1396]: at execSync (node:child_process:959:15) Feb 22 18:07:37 volumio-hr volumio[1396]: at /data/plugins/user_interface/peppyspectrum/index.js:770:19 Feb 22 18:07:37 volumio-hr volumio[1396]: at new Promise () Feb 22 18:07:37 volumio-hr volumio[1396]: at peppyspectrum.dlspectrum (/data/plugins/user_interface/peppyspectrum/index.js:745:12) Feb 22 18:07:37 volumio-hr volumio[1396]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 22 18:07:37 volumio-hr volumio[1396]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Feb 22 18:07:37 volumio-hr volumio[1396]: at Socket.emit (node:events:514:28) Feb 22 18:07:37 volumio-hr volumio[1396]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 22 18:07:37 volumio-hr volumio[1396]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 22 18:07:37 volumio-hr volumio[1396]: status: 1, Feb 22 18:07:37 volumio-hr volumio[1396]: signal: null, Feb 22 18:07:37 volumio-hr volumio[1396]: output: [ Feb 22 18:07:37 volumio-hr volumio[1396]: null, Feb 22 18:07:37 volumio-hr volumio[1396]: , Feb 22 18:07:37 volumio-hr volumio[1396]: Feb 22 18:07:37 volumio-hr volumio[1396]: ], Feb 22 18:07:37 volumio-hr volumio[1396]: pid: 6856, Feb 22 18:07:37 volumio-hr volumio[1396]: stdout: , Feb 22 18:07:37 volumio-hr volumio[1396]: stderr: Feb 22 18:07:37 volumio-hr volumio[1396]: } Feb 22 18:07:37 volumio-hr volumio[1396]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 22 18:07:38 volumio-hr sudo[6874]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-22 18:06' Feb 22 18:07:38 volumio-hr sudo[6874]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 22 18:07:38 volumio-hr ntpd[1180]: PROTO: 151.22.209.90 unlink local addr 192.168.1.85 -> 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"