Aug 26 17:30:00 volumio volumio[1246]: verbose: New Socket.io Connection to 192.168.1.12 from 192.168.1.197 UA: Mozilla/5.0 (Linux; Android 17; Pixel 7 Build/CP2A.260705.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 26 17:30:00 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Aug 26 17:30:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 17:30:00 volumio volumio[1246]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 26 17:30:00 volumio volumio[1246]: info: Listing playlists Aug 26 17:30:00 volumio volumio[1246]: info: CoreCommandRouter::volumioGetQueue Aug 26 17:30:00 volumio volumio[1246]: info: CoreStateMachine::getQueue Aug 26 17:30:00 volumio volumio[1246]: info: CorePlayQueue::getQueue Aug 26 17:30:00 volumio volumio[1246]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 17:30:00 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 17:30:03 volumio volumio[1246]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/bookworm/armhf Aug 26 17:30:03 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:03.273+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/peppymeterbasic/volumio/bookworm/armhf Aug 26 17:30:05 volumio volumio[1246]: info: Folder /tmp/plugins removed Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.340+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.497+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: Check plugin dependencies Aug 26 17:30:05 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.525+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: Checking if plugin already exists Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.529+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: Rename folder Aug 26 17:30:05 volumio volumio[1246]: info: Folder /tmp/downloaded_plugin.zip removed Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.546+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: Move to category Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.820+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio volumio[1246]: info: Checking if install.sh is present Aug 26 17:30:05 volumio volumio[1246]: info: Executing install.sh Aug 26 17:30:05 volumio sudo[26482]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppymeterbasic/install.sh Aug 26 17:30:05 volumio sudo[26482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:05 volumio volumio[1246]: info: Installing peppyalsa plugin dependencies Aug 26 17:30:05 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:05.863+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:05 volumio sudo[26486]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Aug 26 17:30:05 volumio sudo[26486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:06 volumio volumio[1246]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Aug 26 17:30:06 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:06.066+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:06 volumio volumio[1246]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Aug 26 17:30:06 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:06.070+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:09 volumio volumio[1246]: info: Reading package lists... Aug 26 17:30:09 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:09.671+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:09 volumio sudo[26486]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:09 volumio volumio[1246]: info: cloning peppymeter repo Aug 26 17:30:09 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:09.739+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:11 volumio go-librespot[1767]: time="2026-08-26T17:30:11+02:00" level=trace msg="sent dealer ping" Aug 26 17:30:11 volumio go-librespot[1767]: time="2026-08-26T17:30:11+02:00" level=trace msg="received dealer pong" Aug 26 17:30:19 volumio sudo[26650]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chown -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Aug 26 17:30:19 volumio sudo[26650]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:20 volumio sudo[26650]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:20 volumio sudo[26652]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chgrp -R volumio /data/plugins/user_interface/peppymeterbasic /data/INTERNAL/PeppyMeterBasic/Templates Aug 26 17:30:20 volumio sudo[26652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:20 volumio sudo[26652]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:20 volumio volumio[1246]: info: installing apt packages Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.048+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio sudo[26654]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -yf install python3-pygame python3-pip python3-dev libjpeg-dev zlib1g-dev libfftw3-dev Aug 26 17:30:20 volumio sudo[26654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:20 volumio volumio[1246]: info: Reading package lists... Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.165+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio volumio[1246]: info: Building dependency tree... Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.667+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio volumio[1246]: info: Reading state information... Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.676+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio volumio[1246]: info: python3-pip is already the newest version (23.0.1+dfsg-1+rpt1). Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.715+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio volumio[1246]: info: You might want to run 'apt --fix-broken install' to correct these. Aug 26 17:30:20 volumio volumio[1246]: info: The following packages have unmet dependencies: Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.717+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:20 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:20.717+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: libfftw3-dev : Depends: libfftw3-bin (= 3.3.10-1) but it is not going to be installed Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.259+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: Depends: libfftw3-double3 (= 3.3.10-1) but it is not going to be installed Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.263+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: libjpeg-dev : Depends: libjpeg62-turbo-dev (>= 1:2.1.5-2) but it is not going to be installed Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.269+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: libpython3.11 : Depends: libpython3.11-stdlib (= 3.11.2-6+deb12u8) but 3.11.2-6+deb12u6 is to be installed Aug 26 17:30:21 volumio volumio[1246]: info: libpython3.11-stdlib : Depends: libpython3.11-minimal (= 3.11.2-6+deb12u6) but 3.11.2-6+deb12u8 is to be installed Aug 26 17:30:21 volumio volumio[1246]: info: python3-dev : Depends: libpython3-dev (= 3.11.2-1) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: Depends: python3.11-dev (>= 3.11.2-1~) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: python3-pygame : Depends: fonts-freefont-ttf but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: Depends: python3-numpy (>= 1:1.22.0) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: Depends: python3-numpy-abi9 Aug 26 17:30:21 volumio volumio[1246]: info: Depends: libportmidi0 but it is not going to be installed Aug 26 17:30:21 volumio sudo[26654]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:21 volumio volumio[1246]: info: Depends: libsdl2-image-2.0-0 (>= 2.0.5) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: Depends: libsdl2-mixer-2.0-0 (>= 2.0.4) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: Depends: libsdl2-ttf-2.0-0 (>= 2.0.15) but it is not going to be installed Aug 26 17:30:21 volumio volumio[1246]: info: python3.11 : Depends: libpython3.11-stdlib (= 3.11.2-6+deb12u8) but 3.11.2-6+deb12u6 is to be installed Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.277+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.277+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.277+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.277+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.278+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.278+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.278+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.278+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.279+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.279+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.279+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.279+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: zlib1g-dev : Depends: libc6-dev but it is not going to be installed or Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.305+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio volumio[1246]: info: libc-dev Aug 26 17:30:21 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:21.307+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:21 volumio sudo[26660]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Aug 26 17:30:21 volumio sudo[26660]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:21 volumio systemd[1]: Reloading. Aug 26 17:30:21 volumio systemd[1]: /etc/systemd/system/musicservicesshield.service:14: Failed to parse output specifier, ignoring: jouurnal Aug 26 17:30:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 17:30:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 26 17:30:22 volumio 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. Aug 26 17:30:22 volumio 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. Aug 26 17:30:22 volumio sudo[26660]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:22 volumio volumio[1246]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so Aug 26 17:30:22 volumio volumio[1246]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0 Aug 26 17:30:22 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:22.261+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:22 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:22.263+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:23 volumio volumio[1246]: info: Tunnel connection is inactive, restarting it Aug 26 17:30:23 volumio volumio[1246]: info: Starting Tunnel 1 Aug 26 17:30:23 volumio volumio[1246]: info: Starting Tunnel Connection Checker Aug 26 17:30:23 volumio sudo[26688]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 26 17:30:23 volumio sudo[26688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:23 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:23 volumio sudo[26688]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:23 volumio volumio[1246]: info: Remote SSH Started Aug 26 17:30:23 volumio autossh[26691]: port set to 0, monitoring disabled Aug 26 17:30:23 volumio autossh[26691]: starting ssh (count 1) Aug 26 17:30:23 volumio autossh[26691]: ssh child pid is 26694 Aug 26 17:30:23 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 26 17:30:23 volumio volumio[1246]: info: CoreCommandRouter::volumioGetState Aug 26 17:30:23 volumio volumiossh-tunnel[26694]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 26 17:30:23 volumio autossh[26691]: ssh exited prematurely with status 255; autossh exiting Aug 26 17:30:23 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:23 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:23 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 1. Aug 26 17:30:23 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio autossh[26696]: port set to 0, monitoring disabled Aug 26 17:30:24 volumio autossh[26696]: starting ssh (count 1) Aug 26 17:30:24 volumio autossh[26696]: ssh child pid is 26699 Aug 26 17:30:24 volumio volumiossh-tunnel[26699]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 26 17:30:24 volumio autossh[26696]: ssh exited prematurely with status 255; autossh exiting Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 2. Aug 26 17:30:24 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio autossh[26701]: port set to 0, monitoring disabled Aug 26 17:30:24 volumio autossh[26701]: starting ssh (count 1) Aug 26 17:30:24 volumio autossh[26701]: ssh child pid is 26704 Aug 26 17:30:24 volumio volumiossh-tunnel[26704]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 26 17:30:24 volumio autossh[26701]: ssh exited prematurely with status 255; autossh exiting Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 3. Aug 26 17:30:24 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio autossh[26706]: port set to 0, monitoring disabled Aug 26 17:30:24 volumio autossh[26706]: starting ssh (count 1) Aug 26 17:30:24 volumio autossh[26706]: ssh child pid is 26709 Aug 26 17:30:24 volumio volumiossh-tunnel[26709]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 26 17:30:24 volumio autossh[26706]: ssh exited prematurely with status 255; autossh exiting Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 4. Aug 26 17:30:24 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio autossh[26711]: port set to 0, monitoring disabled Aug 26 17:30:24 volumio autossh[26711]: starting ssh (count 1) Aug 26 17:30:24 volumio autossh[26711]: ssh child pid is 26714 Aug 26 17:30:24 volumio volumiossh-tunnel[26714]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 26 17:30:24 volumio autossh[26711]: ssh exited prematurely with status 255; autossh exiting Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Scheduled restart job, restart counter is at 5. Aug 26 17:30:24 volumio systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Start request repeated too quickly. Aug 26 17:30:24 volumio systemd[1]: sshtunnel.service: Failed with result 'exit-code'. Aug 26 17:30:24 volumio systemd[1]: Failed to start sshtunnel.service - MyVolumio SSH Tunnel. Aug 26 17:30:25 volumio sudo[26730]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Aug 26 17:30:25 volumio sudo[26730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:25 volumio sudo[26730]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:25 volumio sudo[26482]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:25 volumio volumio[1246]: Plugin install end detected on script Aug 26 17:30:25 volumio volumio[1246]: info: Install script completed Aug 26 17:30:25 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:25.814+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:25 volumio volumio[1246]: info: Adding reference to registry Aug 26 17:30:25 volumio volumio5-onboarding[1592]: time=2026-08-26T17:30:25.818+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 26 17:30:25 volumio volumio[1246]: info: Done installing plugin. Aug 26 17:30:25 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 17:30:25 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 26 17:30:25 volumio volumio[1246]: info: Folder /tmp/plugins removed Aug 26 17:30:25 volumio volumio[1246]: info: Folder /tmp/downloaded_plugin.zip removed Aug 26 17:30:25 volumio volumio[1246]: info: Folder /data/temp removed Aug 26 17:30:29 volumio volumio[1246]: info: Enabling plugin peppymeterbasic Aug 26 17:30:29 volumio volumio[1246]: info: Loading plugin "peppymeterbasic"... Aug 26 17:30:29 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file Aug 26 17:30:29 volumio volumio[1246]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 17:30:29 volumio volumio[1246]: info: Reading ALSA contributions from plugins. Aug 26 17:30:29 volumio volumio[1246]: info: Asound.conf file written Aug 26 17:30:29 volumio sudo[26734]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 17:30:29 volumio sudo[26734]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26734]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting MPD Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:29 volumio sudo[26740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:30:29 volumio sudo[26740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26740]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio sudo[26742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:30:29 volumio sudo[26742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 17:30:29 volumio volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: PLUGIN START: peppymeterbasic Aug 26 17:30:29 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Aug 26 17:30:29 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Aug 26 17:30:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 26 17:30:29 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file Aug 26 17:30:29 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 17:30:29 volumio systemd[1]: mpd.service: Consumed 3min 23.025s CPU time. Aug 26 17:30:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 17:30:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 17:30:29 volumio volumio[1246]: info: Done. Aug 26 17:30:29 volumio sudo[26752]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Aug 26 17:30:29 volumio sudo[26752]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio volumio[1246]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 17:30:29 volumio volumio[1246]: info: Reading ALSA contributions from plugins. Aug 26 17:30:29 volumio volumio[1246]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 26 17:30:29 volumio volumio[1246]: info: MPD Permissions set Aug 26 17:30:29 volumio volumio[1246]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio sudo[26752]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 17:30:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 17:30:29 volumio volumio[1246]: info: snd_dummy loaded Aug 26 17:30:29 volumio volumio[1246]: info: Starting Shairport Sync Aug 26 17:30:29 volumio (udev-worker)[26764]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Aug 26 17:30:29 volumio volumio[1246]: info: Asound.conf file unchanged, so no further update is needed Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting MPD Aug 26 17:30:29 volumio sudo[26767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:29 volumio sudo[26767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:30:29 volumio sudo[26769]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26769]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio sudo[26773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:30:29 volumio sudo[26773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 17:30:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:29 volumio systemd[1]: shairport-sync.service: Consumed 2.156s CPU time. Aug 26 17:30:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:29 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 17:30:29 volumio volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio sudo[26767]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio sudo[26761]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:30:29 volumio volumio[1246]: info: Preparing to generate the ALSA configuration file Aug 26 17:30:29 volumio sudo[26761]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:29 volumio sudo[26761]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 17:30:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 17:30:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 17:30:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 17:30:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 17:30:29 volumio volumio[1246]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 26 17:30:29 volumio volumio[1246]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 26 17:30:29 volumio volumio[1246]: info: Reading ALSA contributions from plugins. Aug 26 17:30:29 volumio volumio[1246]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 26 17:30:29 volumio volumio[1246]: info: MPD Permissions set Aug 26 17:30:29 volumio volumio[1246]: info: Shairport-Sync Started Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: Starting Shairport Sync Aug 26 17:30:29 volumio volumio[1246]: info: Asound.conf file written Aug 26 17:30:29 volumio sudo[26809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 17:30:29 volumio sudo[26809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 17:30:29 volumio sudo[26810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26810]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio sudo[26795]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:30:29 volumio sudo[26795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:29 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 17:30:29 volumio sudo[26795]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 17:30:29 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: No state is present for card Loopback Aug 26 17:30:29 volumio volumio[1246]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Aug 26 17:30:29 volumio volumio[1246]: Found hardware: "Loopback" "Loopback Mixer" "" "" "" Aug 26 17:30:29 volumio volumio[1246]: Hardware is initialized using a generic method Aug 26 17:30:29 volumio volumio[1246]: No state is present for card Loopback Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting MPD Aug 26 17:30:29 volumio volumio[1246]: info: Output device has changed, restarting Shairport Sync Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:29 volumio sudo[26819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 17:30:29 volumio sudo[26819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio sudo[26819]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:29 volumio sudo[26822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 17:30:29 volumio sudo[26822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:29 volumio volumio[1246]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 26 17:30:29 volumio volumio[1246]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: MPD Permissions set Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:29 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:30 volumio sudo[26809]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:30 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 26 17:30:30 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 26 17:30:30 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 17:30:30 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 17:30:30 volumio volumio[1246]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 26 17:30:30 volumio volumio[1246]: info: Shairport-Sync Started Aug 26 17:30:30 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 26 17:30:30 volumio volumio[1246]: info: Starting Shairport Sync Aug 26 17:30:30 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 26 17:30:30 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 26 17:30:30 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 26 17:30:30 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 26 17:30:30 volumio sudo[26843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 17:30:30 volumio sudo[26843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:30 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 26 17:30:30 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 26 17:30:30 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:30 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 26 17:30:30 volumio sudo[26843]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:30 volumio volumio[1246]: info: Shairport-Sync Started Aug 26 17:30:30 volumio volumio[1246]: info: camilladsp respawn in 100 ms (attempt 1/10) Aug 26 17:30:30 volumio sudo[26837]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 26 17:30:30 volumio sudo[26837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 26 17:30:30 volumio sudo[26837]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:31 volumio mpd[26870]: 2026-08-26T17:30:31 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 17:30:31 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 26 17:30:31 volumio sudo[26742]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:31 volumio sudo[26822]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:31 volumio sudo[26773]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:31 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Aug 26 17:30:31 volumio volumio[1246]: assert.ok(self.idling) Aug 26 17:30:31 volumio volumio[1246]: error: The expression evaluated to a falsy value: Aug 26 17:30:31 volumio volumio[1246]: assert.ok(self.idling) Aug 26 17:30:31 volumio volumio[1246]: error: MPD error: The expression evaluated to a falsy value: Aug 26 17:30:31 volumio volumio[1246]: assert.ok(self.idling) Aug 26 17:30:31 volumio volumio[1246]: error: The expression evaluated to a falsy value: Aug 26 17:30:31 volumio volumio[1246]: assert.ok(self.idling) Aug 26 17:30:31 volumio volumio[1246]: error: updateQueue error: null Aug 26 17:30:31 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Aug 26 17:30:31 volumio bash[26885]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 36: cset: command not found Aug 26 17:30:31 volumio bash[26886]: /data/plugins/system_hardware/music_services_shield/moveprocess.sh: 5: cset: not found Aug 26 17:30:31 volumio bash[26890]: /data/plugins/system_hardware/music_services_shield/moveallprocesses.sh: line 49: cset: command not found Aug 26 17:30:31 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Aug 26 17:30:41 volumio go-librespot[1767]: time="2026-08-26T17:30:41+02:00" level=trace msg="sent dealer ping" Aug 26 17:30:41 volumio go-librespot[1767]: time="2026-08-26T17:30:41+02:00" level=trace msg="received dealer pong" Aug 26 17:30:41 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Aug 26 17:30:42 volumio go-librespot[1767]: time="2026-08-26T17:30:42+02:00" level=trace msg="received accesspoint ping" Aug 26 17:30:43 volumio go-librespot[1767]: time="2026-08-26T17:30:43+02:00" level=trace msg="received accesspoint pong ack" Aug 26 17:30:57 volumio volumio[1246]: info: CoreCommandRouter::volumioToggle Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::stop Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Aug 26 17:30:57 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:57 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:57 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:30:57 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:57 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:57 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:57 volumio volumio[1246]: info: CoreStateMachine::serviceStop Aug 26 17:30:57 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:57 volumio volumio[1246]: info: CoreCommandRouter::serviceStop Aug 26 17:30:57 volumio volumio[1246]: info: [1787758257465] ControllerWebradio::stop Aug 26 17:30:57 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 17:30:57 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 26 17:30:57 volumio volumio[1246]: info: sendMpdCommand stop took 21 milliseconds Aug 26 17:30:57 volumio sudo[26946]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 17:30:57 volumio sudo[26946]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:57 volumio sudo[26946]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:57 volumio volumio[1246]: info: peppymeterbasic Daemon Stop Aug 26 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioToggle Aug 26 17:30:58 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:58 volumio volumio[1246]: info: CoreStateMachine::play index undefined Aug 26 17:30:58 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:30:58 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:58 volumio volumio[1246]: info: CoreStateMachine::startPlaybackTimer Aug 26 17:30:58 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 17:30:58 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 26 17:30:58 volumio volumio[1246]: info: [1787758258614] ControllerWebradio::clearAddPlayTrack Aug 26 17:30:58 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 17:30:58 volumio volumio[1246]: info: sendMpdCommand stop took 1 milliseconds Aug 26 17:30:58 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand clear Aug 26 17:30:58 volumio volumio[1246]: info: Aug 26 17:30:58 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:58 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:58 volumio volumio[1246]: info: sendMpdCommand clear took 0 milliseconds Aug 26 17:30:58 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand load "https://icstream.rds.radio/rdsrelax" Aug 26 17:30:58 volumio volumio[1246]: info: Aug 26 17:30:58 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:58 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:58 volumio volumio[1246]: info: Aug 26 17:30:58 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:58 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:58 volumio volumio[1246]: error: updateQueue error: null Aug 26 17:30:58 volumio volumio[1246]: info: ------------------------------ 2ms Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand add "https://icstream.rds.radio/rdsrelax" Aug 26 17:30:59 volumio volumio[1246]: error: updateQueue error: null Aug 26 17:30:59 volumio volumio[1246]: error: updateQueue error: null Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 549ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 548ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand add "https://icstream.rds.radio/rdsrelax" took 1 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand play Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand play took 3 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 2ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 2ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 1ms Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 12 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 14 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 14 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 2 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rdsrelax","artist":null,"album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus stop Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 19ms Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 6 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 9 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 7 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rdsrelax","artist":null,"album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rdsrelax","artist":null,"album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"rdsrelax","artist":null,"album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 56ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 55ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 43ms Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 32 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 60ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 57ms Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26959]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26959]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26964]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26964]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio sudo[26967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio sudo[26974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26961]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26959]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio sudo[26984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: camilladsp respawn in 2000 ms (attempt 2/10) Aug 26 17:30:59 volumio volumio[1246]: info: FusionDsp - ---- read samplerate, raw: 48000,S32_LE,2,32 Aug 26 17:30:59 volumio volumio[1246]: info: FusionDsp - ---- read samplerate from file: 48000 Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 234ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 234 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 234ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 234 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 216ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 215 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 215ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 215 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces system playlist update Aug 26 17:30:59 volumio volumio[1246]: info: Ignoring MPD Status Update Aug 26 17:30:59 volumio volumio[1246]: info: Aug 26 17:30:59 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:30:59 volumio sudo[26989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:30:59 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 16ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 16 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 15ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand status took 15 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[26983]: Traceback (most recent call last): Aug 26 17:30:59 volumio volumio[26983]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Aug 26 17:30:59 volumio volumio[26983]: import pygame Aug 26 17:30:59 volumio volumio[26983]: ModuleNotFoundError: No module named 'pygame' Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1236,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:59 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 302ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 302ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 284ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 284ms Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 26 17:30:59 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 51 milliseconds Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:30:59 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:30:59 volumio volumio[1246]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1730,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Link*BASE ATTUALITA' (RELAX)*La tua oasi di Relax**4d7eff32-b1b7-4ea6-8c23-af29f0f1c402","artist":"RDS Relax","album":null,"uri":"https://icstream.rds.radio/rdsrelax","trackType":""} Aug 26 17:30:59 volumio volumio[1246]: verbose: CURRENT POSITION 0 Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState stateService play Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::syncState currentStatus play Aug 26 17:30:59 volumio volumio[1246]: info: Received an update from plugin. extracting info from payload Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:30:59 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:30:59 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 85ms Aug 26 17:30:59 volumio volumio[1246]: info: ------------------------------ 85ms Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 26 17:30:59 volumio sudo[26964]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26969]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26967]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26974]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26980]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26978]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26984]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio sudo[26989]: pam_unix(sudo:session): session closed for user root Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[26995]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[26994]: Traceback (most recent call last): Aug 26 17:30:59 volumio volumio[26994]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Aug 26 17:30:59 volumio volumio[26994]: import pygame Aug 26 17:30:59 volumio volumio[26994]: ModuleNotFoundError: No module named 'pygame' Aug 26 17:30:59 volumio sudo[26997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[26997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:30:59 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[27001]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[27001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[27003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[27003]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:30:59 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:30:59 volumio sudo[27007]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:30:59 volumio sudo[27007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:31:00 volumio sudo[27010]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27010]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:31:00 volumio sudo[27013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 26 17:31:00 volumio sudo[27013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:31:00 volumio sudo[27016]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:31:00 volumio sudo[27020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio sudo[27023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio sudo[26995]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 26 17:31:00 volumio volumio[27021]: Traceback (most recent call last): Aug 26 17:31:00 volumio volumio[27021]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Aug 26 17:31:00 volumio volumio[27021]: import pygame Aug 26 17:31:00 volumio volumio[27021]: ModuleNotFoundError: No module named 'pygame' Aug 26 17:31:00 volumio sudo[27027]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 26 17:31:00 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:31:00 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Aug 26 17:31:00 volumio sudo[27030]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 26 17:31:00 volumio sudo[27030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: error: FusionDsp - Reload WebSocket error: [object Object] Aug 26 17:31:00 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Aug 26 17:31:00 volumio sudo[27020]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27007]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27027]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27030]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[26997]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27023]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27016]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27001]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27013]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio sudo[27003]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio sudo[27010]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[1246]: info: peppymeterbasic Daemon Started Aug 26 17:31:00 volumio volumio[27035]: Traceback (most recent call last): Aug 26 17:31:00 volumio volumio[27035]: File "/data/plugins/user_interface/peppymeterbasic/BasicPeppyMeter/peppymeter.py", line 18, in Aug 26 17:31:00 volumio volumio[27035]: import pygame Aug 26 17:31:00 volumio volumio[27035]: ModuleNotFoundError: No module named 'pygame' Aug 26 17:31:00 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=exited, status=1/FAILURE Aug 26 17:31:00 volumio systemd[1]: peppymeterbasic.service: Failed with result 'exit-code'. Aug 26 17:31:01 volumio volumio[1246]: info: camilladsp respawn in 4000 ms (attempt 3/10) Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::volumioStop Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::stop Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::stPlaybackTimer Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::updateTrackBlock Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrackBlock Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::serviceStop Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::serviceStop Aug 26 17:31:05 volumio volumio[1246]: info: [1787758265071] ControllerWebradio::stop Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand stop Aug 26 17:31:05 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 26 17:31:05 volumio volumio[1246]: info: Aug 26 17:31:05 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand stop took 22 milliseconds Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand status took 1 milliseconds Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:31:05 volumio volumio[1246]: info: Aug 26 17:31:05 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:31:05 volumio volumio[1246]: info: Aug 26 17:31:05 volumio volumio[1246]: ---------------------------- MPD announces state update: player Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::getState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand status Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand status took 1 milliseconds Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand status took 1 milliseconds Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseState Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 17:31:05 volumio volumio[1246]: info: ------------------------------ 12ms Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 17:31:05 volumio volumio[1246]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:31:05 volumio volumio[1246]: verbose: ControllerMpd::parseTrackInfo Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 17:31:05 volumio volumio[1246]: info: ControllerMpd::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::servicePushState Aug 26 17:31:05 volumio volumio[1246]: info: CoreStateMachine::pushState Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: CoreCommandRouter::volumioPushState Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output update for this device Aug 26 17:31:05 volumio volumio[1246]: info: MRS: Pushing multiroomSync output Aug 26 17:31:05 volumio volumio[1246]: info: CorePlayQueue::getTrack 0 Aug 26 17:31:05 volumio volumio[1246]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Aug 26 17:31:05 volumio sudo[27046]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 17:31:05 volumio sudo[27046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:05 volumio volumio[1246]: info: ------------------------------ 22ms Aug 26 17:31:05 volumio volumio[1246]: info: ------------------------------ 22ms Aug 26 17:31:05 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 26 17:31:05 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 26 17:31:05 volumio sudo[27046]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:05 volumio volumio[1246]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 26 17:31:05 volumio sudo[27049]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 17:31:05 volumio sudo[27049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:05 volumio volumio[1246]: info: peppymeterbasic Daemon Stop Aug 26 17:31:05 volumio sudo[27051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 17:31:05 volumio sudo[27051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:05 volumio sudo[27053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 26 17:31:05 volumio sudo[27053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 26 17:31:05 volumio sudo[27051]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:05 volumio sudo[27049]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:05 volumio volumio[1246]: info: peppymeterbasic Daemon Stop Aug 26 17:31:05 volumio volumio[1246]: info: peppymeterbasic Daemon Stop Aug 26 17:31:05 volumio sudo[27053]: pam_unix(sudo:session): session closed for user root Aug 26 17:31:05 volumio volumio[1246]: info: peppymeterbasic Daemon Stop Aug 26 17:31:11 volumio go-librespot[1767]: time="2026-08-26T17:31:11+02:00" level=trace msg="sent dealer ping" Aug 26 17:31:11 volumio go-librespot[1767]: time="2026-08-26T17:31:11+02:00" level=trace msg="received dealer pong" Aug 26 17:31:16 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:31:27 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Aug 26 17:31:39 volumio volumio[1246]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Aug 26 17:31:39 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Aug 26 17:31:39 volumio volumio[1246]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Aug 26 17:31:39 volumio volumio[1246]: --2026-08-26 17:31:39-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Aug 26 17:31:39 volumio volumio[1246]: Resolving github.com (github.com)... 140.82.121.4 Aug 26 17:31:39 volumio volumio[1246]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Aug 26 17:31:39 volumio volumio[1246]: HTTP request sent, awaiting response... 302 Found Aug 26 17:31:39 volumio volumio[1246]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Aug 26 17:31:39 volumio volumio[1246]: --2026-08-26 17:31:39-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Aug 26 17:31:39 volumio volumio[1246]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.110.133, 185.199.108.133, ... Aug 26 17:31:39 volumio volumio[1246]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Aug 26 17:31:39 volumio volumio[1246]: HTTP request sent, awaiting response... 200 OK Aug 26 17:31:39 volumio volumio[1246]: Length: 362 [text/plain] Aug 26 17:31:39 volumio volumio[1246]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Aug 26 17:31:39 volumio volumio[1246]: 0K 100% 9.51M=0s Aug 26 17:31:39 volumio volumio[1246]: 2026-08-26 17:31:39 (9.51 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Aug 26 17:31:39 volumio volumio[1246]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 17:31:39 volumio volumio[1246]: info: CoreCommandRouter::Close All Modals sent Aug 26 17:31:41 volumio go-librespot[1767]: time="2026-08-26T17:31:41+02:00" level=trace msg="sent dealer ping" Aug 26 17:31:41 volumio go-librespot[1767]: time="2026-08-26T17:31:41+02:00" level=trace msg="received dealer pong" Aug 26 17:31:47 volumio volumio[1246]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Aug 26 17:31:47 volumio volumio[1246]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Aug 26 17:31:50 volumio volumio[1246]: --2026-08-26 17:31:47-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Aug 26 17:31:50 volumio volumio[1246]: Resolving github.com (github.com)... 140.82.121.4 Aug 26 17:31:50 volumio volumio[1246]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Aug 26 17:31:50 volumio volumio[1246]: HTTP request sent, awaiting response... 404 Not Found Aug 26 17:31:50 volumio volumio[1246]: 2026-08-26 17:31:48 ERROR 404: Not Found. Aug 26 17:31:50 volumio volumio[1246]: --2026-08-26 17:31:48-- http://a/ Aug 26 17:31:50 volumio volumio[1246]: Resolving a (a)... failed: Device or resource busy. Aug 26 17:31:50 volumio volumio[1246]: wget: unable to resolve host address ‘a’ Aug 26 17:31:50 volumio volumio[1246]: --2026-08-26 17:31:48-- http://packet.zip/ Aug 26 17:31:50 volumio volumio[1246]: Resolving packet.zip (packet.zip)... 185.126.158.214 Aug 26 17:31:50 volumio volumio[1246]: Connecting to packet.zip (packet.zip)|185.126.158.214|:80... connected. Aug 26 17:31:50 volumio volumio[1246]: HTTP request sent, awaiting response... 301 Moved Permanently Aug 26 17:31:50 volumio volumio[1246]: Location: https://www.packet.zip/ [following] Aug 26 17:31:50 volumio volumio[1246]: --2026-08-26 17:31:48-- https://www.packet.zip/ Aug 26 17:31:50 volumio volumio[1246]: Resolving www.packet.zip (www.packet.zip)... 185.126.158.214 Aug 26 17:31:50 volumio volumio[1246]: Connecting to www.packet.zip (www.packet.zip)|185.126.158.214|:443... connected. Aug 26 17:31:50 volumio volumio[1246]: HTTP request sent, awaiting response... 200 OK Aug 26 17:31:50 volumio volumio[1246]: Length: unspecified [text/html] Aug 26 17:31:50 volumio volumio[1246]: Saving to: ‘/tmp/index.html’ Aug 26 17:31:50 volumio volumio[1246]: 0K .......... .......... .......... .......... .......... 296K Aug 26 17:31:50 volumio volumio[1246]: 50K .......... .......... .......... .......... .......... 96.4K Aug 26 17:31:50 volumio volumio[1246]: 100K .. 21.9M=0.7s Aug 26 17:31:50 volumio volumio[1246]: 2026-08-26 17:31:50 (149 KB/s) - ‘/tmp/index.html’ saved [105078] Aug 26 17:31:50 volumio volumio[1246]: FINISHED --2026-08-26 17:31:50-- Aug 26 17:31:50 volumio volumio[1246]: Total wall clock time: 3.7s Aug 26 17:31:50 volumio volumio[1246]: Downloaded: 1 files, 103K in 0.7s (149 KB/s) Aug 26 17:31:50 volumio volumio[1246]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove 'a': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:31:50 volumio volumio[1246]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove 'a': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Aug 26 17:31:50 volumio volumio[1246]: at checkExecSyncError (node:child_process:887:11) Aug 26 17:31:50 volumio volumio[1246]: at execSync (node:child_process:959:15) Aug 26 17:31:50 volumio volumio[1246]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Aug 26 17:31:50 volumio volumio[1246]: at new Promise () Aug 26 17:31:50 volumio volumio[1246]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Aug 26 17:31:50 volumio volumio[1246]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Aug 26 17:31:50 volumio volumio[1246]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Aug 26 17:31:50 volumio volumio[1246]: at Socket.emit (node:events:514:28) Aug 26 17:31:50 volumio volumio[1246]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 26 17:31:50 volumio volumio[1246]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 26 17:31:50 volumio volumio[1246]: status: 1, Aug 26 17:31:50 volumio volumio[1246]: signal: null, Aug 26 17:31:50 volumio volumio[1246]: output: [ Aug 26 17:31:50 volumio volumio[1246]: null, Aug 26 17:31:50 volumio volumio[1246]: , Aug 26 17:31:50 volumio volumio[1246]: Aug 26 17:31:50 volumio volumio[1246]: ], Aug 26 17:31:50 volumio volumio[1246]: pid: 27148, Aug 26 17:31:50 volumio volumio[1246]: stdout: , Aug 26 17:31:50 volumio volumio[1246]: stderr: Aug 26 17:31:50 volumio volumio[1246]: } Aug 26 17:31:50 volumio volumio[1246]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 17:31:50 volumio kernel: Bluetooth: hci0: Opcode 0x200a failed: -16 Aug 26 17:31:51 volumio sudo[27167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 17:30' Aug 26 17:31:51 volumio sudo[27167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"