Mar 27 11:25:03 display volumio[1365]: info: Selecting previously unselected package python3-pygame.
Mar 27 11:25:03 display volumio5-onboarding[2385]: time=2026-03-27T11:25:03.346-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:03 display volumio[1365]: info: Preparing to unpack .../33-python3-pygame_2.1.2+dfsg-5_armhf.deb ...
Mar 27 11:25:03 display volumio5-onboarding[2385]: time=2026-03-27T11:25:03.349-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:03 display volumio[1365]: info: Unpacking python3-pygame (2.1.2+dfsg-5) ...
Mar 27 11:25:03 display volumio5-onboarding[2385]: time=2026-03-27T11:25:03.366-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up libsdl2-image-2.0-0:armhf (2.6.3+dfsg-1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.606-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up libabsl20220623:armhf (20220623.1-1+rpi1+deb12u2) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.628-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up manpages (6.03-2) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.655-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up fonts-freefont-ttf (20120503-10) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.675-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up linux-libc-dev (1:6.12.75-1+rpt1~bookworm) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.705-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up libtirpc-dev:armhf (1.3.3+ds-1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.726-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up rpcsvc-proto (1.4.3-1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.760-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up libportmidi0:armhf (1:217-6.1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.791-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up libgav1-1:armhf (0.18.0-1+rpi1+b1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.828-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:04 display volumio[1365]: info: Setting up python3-numpy (1:1.24.2-1+deb12u1) ...
Mar 27 11:25:04 display volumio5-onboarding[2385]: time=2026-03-27T11:25:04.856-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 27 11:25:06 display volumio[1365]: info: Discovery: Getting this device information
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:06 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 27 11:25:06 display volumio[1365]: verbose: New Socket.io Connection to 10.0.0.199:3000 from 10.0.0.205 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Mar 27 11:25:06 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Mar 27 11:25:06 display volumio[1365]: info: Setting up libfftw3-double3:armhf (3.3.10-1) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.883-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libnsl-dev:armhf (1.3.0-2) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.899-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libcrypt-dev:armhf (1:4.4.33-2) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.911-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libsdl2-ttf-2.0-0:armhf (2.20.1+dfsg-2) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.928-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libc-dev-bin (2.36-9+rpt2+deb12u13) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.944-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libyuv0:armhf (0.0~git20230123.b2528b0-1) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.954-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libopusfile0:armhf (0.12-4) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.968-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up manpages-dev (6.03-2) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.982-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:06 display volumio[1365]: info: Setting up libavif15:armhf (0.11.1-1+deb12u1) ...
Mar 27 11:25:06 display volumio5-onboarding[2385]: time=2026-03-27T11:25:06.994-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libfftw3-bin (3.3.10-1) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.008-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libsdl2-mixer-2.0-0:armhf (2.6.2+dfsg-2) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.021-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libgd3:armhf (2.3.3-9) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.094-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up python3-pygame (2.1.2+dfsg-5) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.168-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libc6-dev:armhf (2.36-9+rpt2+deb12u13) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.420-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libfftw3-dev:armhf (3.3.10-1) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.494-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libc-devtools (2.36-9+rpt2+deb12u13) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.566-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libexpat1-dev:armhf (2.5.0-1+deb12u2) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.603-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libjpeg62-turbo-dev:armhf (1:2.1.5-2) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.680-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up zlib1g-dev:armhf (1:1.2.13.dfsg-1+rpt1) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.758-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libjpeg-dev:armhf (1:2.1.5-2) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.840-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:07 display volumio[1365]: info: Setting up libpython3.11-dev:armhf (3.11.2-6+deb12u6) ...
Mar 27 11:25:07 display volumio5-onboarding[2385]: time=2026-03-27T11:25:07.924-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: Setting up libpython3-dev:armhf (3.11.2-1) ...
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.009-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: Setting up python3.11-dev (3.11.2-6+deb12u6) ...
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.092-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: Setting up python3-dev (3.11.2-1) ...
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.136-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ...
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.222-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: ldconfig: /lib/arm-linux-gnueabihf/libnss_mdns_minimal.so.2 is not a symbolic link
Mar 27 11:25:08 display volumio[1365]: info:
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.534-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.537-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:08 display volumio[1365]: info: Processing triggers for man-db (2.11.2-2) ...
Mar 27 11:25:08 display volumio5-onboarding[2385]: time=2026-03-27T11:25:08.630-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:09 display volumio[1365]: info: Processing triggers for fontconfig (2.14.1-4) ...
Mar 27 11:25:09 display volumio5-onboarding[2385]: time=2026-03-27T11:25:09.596-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:10 display sudo[4494]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:10 display sudo[5007]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload
Mar 27 11:25:10 display sudo[5007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 11:25:10 display systemd[1]: Reloading.
Mar 27 11:25:10 display 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.
Mar 27 11:25:10 display 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.
Mar 27 11:25:10 display sudo[5007]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:10 display volumio[1365]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so
Mar 27 11:25:10 display volumio5-onboarding[2385]: time=2026-03-27T11:25:10.779-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:10 display volumio[1365]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0
Mar 27 11:25:10 display volumio5-onboarding[2385]: time=2026-03-27T11:25:10.782-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:11 display sudo[5030]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh
Mar 27 11:25:11 display sudo[5030]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 11:25:11 display sudo[5030]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:11 display volumio[1365]: Plugin install end detected on script
Mar 27 11:25:11 display sudo[4322]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:11 display volumio[1365]: info: Install script completed
Mar 27 11:25:11 display volumio5-onboarding[2385]: time=2026-03-27T11:25:11.497-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:11 display volumio[1365]: info: Adding reference to registry
Mar 27 11:25:11 display volumio[1365]: info: Done installing plugin.
Mar 27 11:25:11 display volumio5-onboarding[2385]: time=2026-03-27T11:25:11.503-05:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state
Mar 27 11:25:11 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 11:25:11 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 11:25:11 display volumio[1365]: info: Folder /tmp/plugins removed
Mar 27 11:25:11 display volumio[1365]: info: Folder /tmp/downloaded_plugin.zip removed
Mar 27 11:25:11 display volumio[1365]: info: Folder /data/temp removed
Mar 27 11:25:26 display volumio[1365]: info: Enabling plugin peppymeterbasic
Mar 27 11:25:26 display volumio[1365]: info: Loading plugin "peppymeterbasic"...
Mar 27 11:25:26 display volumio[1365]: info: Preparing to generate the ALSA configuration file
Mar 27 11:25:26 display volumio[1365]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 27 11:25:26 display volumio[1365]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf
Mar 27 11:25:26 display volumio[1365]: info: Reading ALSA contributions from plugins.
Mar 27 11:25:26 display volumio[1365]: info: Asound.conf file written
Mar 27 11:25:26 display sudo[5048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Mar 27 11:25:26 display sudo[5048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display sudo[5048]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Mar 27 11:25:26 display volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2
Mar 27 11:25:26 display volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Mar 27 11:25:26 display volumio[1365]: No state is present for card DAC
Mar 27 11:25:26 display volumio[1365]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2
Mar 27 11:25:26 display volumio[1365]: Found hardware: "I-Sabre_Q2M_DAC" "" "" "" ""
Mar 27 11:25:26 display volumio[1365]: Hardware is initialized using a generic method
Mar 27 11:25:26 display volumio[1365]: No state is present for card DAC
Mar 27 11:25:26 display volumio[1365]: info: Output device has changed, restarting MPD
Mar 27 11:25:26 display volumio[1365]: info: Output device has changed, restarting Shairport Sync
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 11:25:26 display sudo[5057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 27 11:25:26 display sudo[5057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display sudo[5055]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 27 11:25:26 display sudo[5055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display sudo[5055]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 27 11:25:26 display volumio[1365]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 27 11:25:26 display systemd[1]: Stopping mpd.service - Music Player Daemon...
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121
Mar 27 11:25:26 display volumio[1365]: info: QobuzConnect: setDeactiveState invoked
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:26 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:26 display vtcs[3302]: [2026-03-27 11:25:26.838] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected
Mar 27 11:25:26 display vtcs[3302]: [2026-03-27 11:25:26.838] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE
Mar 27 11:25:26 display volumio[1365]: info: PLUGIN START: peppymeterbasic
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Mar 27 11:25:26 display sudo[5067]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 27 11:25:26 display sudo[5067]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display systemd[1]: mpd.service: Deactivated successfully.
Mar 27 11:25:26 display systemd[1]: Stopped mpd.service - Music Player Daemon.
Mar 27 11:25:26 display systemd[1]: mpd.service: Consumed 2.282s CPU time.
Mar 27 11:25:26 display systemd[1]: mpd.socket: Deactivated successfully.
Mar 27 11:25:26 display systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Mar 27 11:25:26 display systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Mar 27 11:25:26 display volumio[1365]: info: Preparing to generate the ALSA configuration file
Mar 27 11:25:26 display volumio[1365]: info: Done.
Mar 27 11:25:26 display sudo[5070]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2
Mar 27 11:25:26 display sudo[5070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display sudo[5070]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 27 11:25:26 display systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 27 11:25:26 display sudo[5076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 27 11:25:26 display sudo[5076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service...
Mar 27 11:25:26 display systemd[1]: vtcs.service: Deactivated successfully.
Mar 27 11:25:26 display systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service.
Mar 27 11:25:26 display volumio[1365]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 27 11:25:26 display volumio[1365]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf
Mar 27 11:25:26 display volumio[1365]: info: Reading ALSA contributions from plugins.
Mar 27 11:25:26 display sudo[5067]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display volumio[1365]: info: snd_dummy loaded
Mar 27 11:25:26 display volumio[1365]: info: MPD Permissions set
Mar 27 11:25:26 display sudo[5089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Mar 27 11:25:26 display sudo[5089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display volumio[1365]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display (udev-worker)[5084]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99.
Mar 27 11:25:26 display sudo[5076]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display sudo[5089]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display sudo[5095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 11:25:26 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:26 display sudo[5095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:26 display sudo[5085]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 27 11:25:26 display sudo[5085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 11:25:26 display sudo[5085]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:26 display volumio[1365]: info: Starting Shairport Sync
Mar 27 11:25:27 display volumio[1365]: info: Asound.conf file unchanged, so no further update is needed
Mar 27 11:25:27 display volumio[1365]: info: Output device has changed, restarting MPD
Mar 27 11:25:27 display sudo[5104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 27 11:25:27 display sudo[5104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service...
Mar 27 11:25:27 display qobuz-connect[3273]: 20260327 11:25:27.012 [3273.3273] INFO SampleApp: Stopping Local configuration server
Mar 27 11:25:27 display volumio[1365]: info: Output device has changed, restarting Shairport Sync
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 11:25:27 display sudo[5107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 27 11:25:27 display sudo[5107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display sudo[5109]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 27 11:25:27 display systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 27 11:25:27 display sudo[5109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 27 11:25:27 display systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 11:25:27 display systemd[1]: shairport-sync.service: Consumed 1.915s CPU time.
Mar 27 11:25:27 display systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 11:25:27 display sudo[5107]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5104]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display volumio[1365]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 27 11:25:27 display systemd[1]: mpd.service: Deactivated successfully.
Mar 27 11:25:27 display systemd[1]: Stopped mpd.service - Music Player Daemon.
Mar 27 11:25:27 display systemd[1]: mpd.socket: Deactivated successfully.
Mar 27 11:25:27 display volumio[1365]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 27 11:25:27 display systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Mar 27 11:25:27 display systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: QobuzConnect: setDeactiveState invoked
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:27 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:27 display systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Mar 27 11:25:27 display systemd[1]: Starting mpd.service - Music Player Daemon...
Mar 27 11:25:27 display sudo[5136]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 27 11:25:27 display sudo[5136]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display sudo[5142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 27 11:25:27 display sudo[5142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display sudo[5148]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service
Mar 27 11:25:27 display volumio[1365]: info: MPD Permissions set
Mar 27 11:25:27 display volumio[1365]: info: Shairport-Sync Started
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display sudo[5148]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display sudo[5136]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5135]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 27 11:25:27 display sudo[5135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Mar 27 11:25:27 display sudo[5135]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 27 11:25:27 display volumio[1365]: info: Starting Shairport Sync
Mar 27 11:25:27 display sudo[5160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 27 11:25:27 display sudo[5148]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display sudo[5142]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service
Mar 27 11:25:27 display sudo[5162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:27 display systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver...
Mar 27 11:25:27 display systemd[1]: shairport-sync.service: Deactivated successfully.
Mar 27 11:25:27 display systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 11:25:27 display systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver.
Mar 27 11:25:27 display sudo[5160]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display volumio[1365]: info: Shairport-Sync Started
Mar 27 11:25:27 display mpd[5157]: 2026-03-27T11:25:27 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 27 11:25:27 display systemd[1]: Started mpd.service - Music Player Daemon.
Mar 27 11:25:27 display sudo[5109]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5057]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display volumio[1365]: error: MPD error: The expression evaluated to a falsy value:
Mar 27 11:25:27 display volumio[1365]: assert.ok(self.idling)
Mar 27 11:25:27 display volumio[1365]: error: The expression evaluated to a falsy value:
Mar 27 11:25:27 display volumio[1365]: assert.ok(self.idling)
Mar 27 11:25:27 display volumio[1365]: error: updateQueue error: null
Mar 27 11:25:27 display qobuz-connect[3273]: 20260327 11:25:27.929 [3273.3273] INFO SampleApp: shat down connection on UNIX socket
Mar 27 11:25:27 display volumio[1365]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected
Mar 27 11:25:27 display volumio[1365]: info: QobuzConnect: setDeactiveState invoked
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:27 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:27 display systemd[1]: qobuz-connect.service: Deactivated successfully.
Mar 27 11:25:27 display systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service.
Mar 27 11:25:27 display systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service.
Mar 27 11:25:27 display sudo[5162]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display sudo[5095]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:27 display volumio[1365]: info: Executing endpoint qc_getconfig
Mar 27 11:25:27 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.006 [5196.5196] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.008 [5196.5196] INFO VolumeManager: [0x10212b8]: Setting new playback volume: 75
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.008 [5196.5196] INFO VolumeManager: [0x10212b8]: Setting new mute state: 0
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.008 [5196.5196] INFO AudioStreamManager: [0x1021010]: Setting new audio download buffer size: 1048576
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.008 [5196.5196] INFO QobuzConnect: [0x1021b80]: Client initialized!
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.008 [5196.5196] INFO SampleApp: Starting Avahi advertising, name: Display, service name: _qobuz-connect._tcp
Mar 27 11:25:28 display volumio[1365]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object]
Mar 27 11:25:28 display volumio[1365]: info: QobuzConnect: QOBUZ Connect daemon connected
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.020 [5196.5196] INFO LocalConfigManager: [0x1020d38]: Starting Local Configuration server
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.020 [5196.5196] INFO SampleApp: Starting Local configuration server
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.021 [5196.5196] INFO SampleApp: Connected to UNIX socket client 0x100b818
Mar 27 11:25:28 display qobuz-connect[5196]: 20260327 11:25:28.156 [5196.5196] INFO SampleApp: Playback volume changed: 75
Mar 27 11:25:28 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:28 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:29 display volumio[1365]: info: TidalConnect service stoped!
Mar 27 11:25:29 display volumio[1365]: info: TidalConnect service stoped!
Mar 27 11:25:29 display volumio[1365]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 27 11:25:29 display volumio[1365]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 27 11:25:29 display sudo[5212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 27 11:25:29 display sudo[5212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:30 display systemd[1]: Started vtcs.service - Volumio Tidal Connect Service.
Mar 27 11:25:30 display sudo[5212]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:30 display volumio[1365]: info: Executing endpoint tc_getconfig
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Mar 27 11:25:30 display vtcs[5215]: STARTING TidalConnect services, version: 1.6.1
Mar 27 11:25:30 display vtcs[5215]: STARTED TidalConnect services.
Mar 27 11:25:30 display volumio[1365]: info: Executing endpoint tc_connect
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Mar 27 11:25:30 display volumio[1365]: info: Connecting to TidalConnect
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::servicePushState
Mar 27 11:25:30 display volumio[1365]: info: CoreStateMachine::pushState
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::volumioPushState
Mar 27 11:25:30 display volumio[1365]: info: MRS: Pushing multiroomSync output update for this device
Mar 27 11:25:30 display volumio[1365]: info: MRS: Pushing multiroomSync output
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::servicePushState
Mar 27 11:25:30 display volumio[1365]: info: CoreStateMachine::pushState
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::volumioPushState
Mar 27 11:25:30 display volumio[1365]: info: MRS: Pushing multiroomSync output update for this device
Mar 27 11:25:30 display volumio[1365]: info: MRS: Pushing multiroomSync output
Mar 27 11:25:30 display volumio[1365]: info: CoreCommandRouter::volumioGetState
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CorePlayQueue::getTrack 3
Mar 27 11:25:30 display volumio[1365]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect
Mar 27 11:25:30 display volumio5-onboarding[2385]: time=2026-03-27T11:25:30.095-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42750 @ 0x2d9ee40" state=STATUS_PLAYING positionMs=37367 volume=100
Mar 27 11:25:30 display volumio5-onboarding[2385]: time=2026-03-27T11:25:30.095-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42750 @ 0x2d9ee40" id=tidal://song/73862558 title="These Dreams"
Mar 27 11:25:30 display volumio5-onboarding[2385]: time=2026-03-27T11:25:30.095-05:00 level=INFO msg="emitting player state changed event" component=server peer="10.0.0.205:42750 @ 0x2d9ee40" state=STATUS_PLAYING positionMs=37367 volume=100
Mar 27 11:25:30 display volumio5-onboarding[2385]: time=2026-03-27T11:25:30.095-05:00 level=INFO msg="emitting player metadata changed event" component=server peer="10.0.0.205:42750 @ 0x2d9ee40" id=tidal://song/73862558 title="These Dreams"
Mar 27 11:25:30 display volumio[1365]: info: PeppyMeterBasic ---peppymeterbasic status play
Mar 27 11:25:30 display volumio[1365]: info: PeppyMeterBasic ---peppymeterbasic status play
Mar 27 11:25:30 display sudo[5231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Mar 27 11:25:30 display sudo[5231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:30 display sudo[5235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service
Mar 27 11:25:30 display sudo[5235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:30 display systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon.
Mar 27 11:25:30 display sudo[5231]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:30 display sudo[5235]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:30 display volumio[1365]: info: peppymeterbasic Daemon Started
Mar 27 11:25:30 display volumio[1365]: info: peppymeterbasic Daemon Started
Mar 27 11:25:30 display volumio[1365]: info: TidalConnect service stoped!
Mar 27 11:25:30 display volumio[1365]: info: TidalConnect service stoped!
Mar 27 11:25:30 display volumio[1365]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 27 11:25:30 display volumio[1365]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 27 11:25:30 display sudo[5249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 27 11:25:30 display sudo[5249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Mar 27 11:25:30 display sudo[5249]: pam_unix(sudo:session): session closed for user root
Mar 27 11:25:33 display volumio[1365]: info: TidalConnect service started!
Mar 27 11:25:33 display volumio[1365]: info: TidalConnect service started!
Mar 27 11:25:33 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 27 11:25:33 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Mar 27 11:25:35 display volumio[1365]: info: [now-playing] Weather not configured: Weather is not set up: add Geographic Coordinates in Localization and an OpenWeatherMap API key in Weather Service.
Mar 27 11:25:36 display volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 27 11:25:44 display volumio[1365]: info: CALLMETHOD: user_interface peppymeterbasic updatelist
Mar 27 11:25:44 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist
Mar 27 11:25:44 display volumio[1365]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt'
Mar 27 11:25:44 display volumio[1365]: --2026-03-27 11:25:44-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt
Mar 27 11:25:44 display volumio[1365]: Resolving github.com (github.com)... 140.82.112.3
Mar 27 11:25:44 display volumio[1365]: Connecting to github.com (github.com)|140.82.112.3|:443... connected.
Mar 27 11:25:44 display volumio[1365]: HTTP request sent, awaiting response... 302 Found
Mar 27 11:25:44 display volumio[1365]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following]
Mar 27 11:25:44 display volumio[1365]: --2026-03-27 11:25:44-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt
Mar 27 11:25:44 display volumio[1365]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.111.133, 185.199.109.133, ...
Mar 27 11:25:44 display volumio[1365]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected.
Mar 27 11:25:44 display volumio[1365]: HTTP request sent, awaiting response... 200 OK
Mar 27 11:25:44 display volumio[1365]: Length: 362 [text/plain]
Mar 27 11:25:44 display volumio[1365]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’
Mar 27 11:25:44 display volumio[1365]: 0K 100% 13.0M=0s
Mar 27 11:25:44 display volumio[1365]: 2026-03-27 11:25:44 (13.0 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362]
Mar 27 11:25:44 display volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 27 11:25:44 display volumio[1365]: info: CoreCommandRouter::Close All Modals sent
Mar 27 11:25:50 display volumio[5239]: libpng warning: iCCP: known incorrect sRGB profile
Mar 27 11:25:58 display volumio[1365]: info: CALLMETHOD: user_interface peppymeterbasic updatelist
Mar 27 11:25:58 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist
Mar 27 11:25:58 display volumio[1365]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt'
Mar 27 11:25:58 display volumio[1365]: --2026-03-27 11:25:58-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt
Mar 27 11:25:58 display volumio[1365]: Resolving github.com (github.com)... 140.82.114.3
Mar 27 11:25:58 display volumio[1365]: Connecting to github.com (github.com)|140.82.114.3|:443... connected.
Mar 27 11:25:58 display volumio[1365]: HTTP request sent, awaiting response... 302 Found
Mar 27 11:25:58 display volumio[1365]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following]
Mar 27 11:25:58 display volumio[1365]: --2026-03-27 11:25:58-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt
Mar 27 11:25:58 display volumio[1365]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.108.133, 185.199.109.133, ...
Mar 27 11:25:58 display volumio[1365]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected.
Mar 27 11:25:58 display volumio[1365]: HTTP request sent, awaiting response... 200 OK
Mar 27 11:25:58 display volumio[1365]: Length: 362 [text/plain]
Mar 27 11:25:58 display volumio[1365]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’
Mar 27 11:25:58 display volumio[1365]: 0K 100% 25.9M=0s
Mar 27 11:25:58 display volumio[1365]: 2026-03-27 11:25:58 (25.9 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362]
Mar 27 11:25:59 display volumio[1365]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 27 11:25:59 display volumio[1365]: info: CoreCommandRouter::Close All Modals sent
Mar 27 11:25:59 display volumio[1365]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object]
Mar 27 11:25:59 display volumio[1365]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter
Mar 27 11:26:03 display volumio[1365]: --2026-03-27 11:25:59-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose
Mar 27 11:26:03 display volumio[1365]: Resolving github.com (github.com)... 140.82.114.3
Mar 27 11:26:03 display volumio[1365]: Connecting to github.com (github.com)|140.82.114.3|:443... connected.
Mar 27 11:26:03 display volumio[1365]: HTTP request sent, awaiting response... 404 Not Found
Mar 27 11:26:03 display volumio[1365]: 2026-03-27 11:26:00 ERROR 404: Not Found.
Mar 27 11:26:03 display volumio[1365]: --2026-03-27 11:26:00-- http://a/
Mar 27 11:26:03 display volumio[1365]: Resolving a (a)... failed: Device or resource busy.
Mar 27 11:26:03 display volumio[1365]: wget: unable to resolve host address ‘a’
Mar 27 11:26:03 display volumio[1365]: --2026-03-27 11:26:01-- http://packet.zip/
Mar 27 11:26:03 display volumio[1365]: Resolving packet.zip (packet.zip)... 162.255.119.167
Mar 27 11:26:03 display volumio[1365]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected.
Mar 27 11:26:03 display volumio[1365]: HTTP request sent, awaiting response... 302 Found
Mar 27 11:26:03 display volumio[1365]: Location: http://www.packet.zip/ [following]
Mar 27 11:26:03 display volumio[1365]: --2026-03-27 11:26:01-- http://www.packet.zip/
Mar 27 11:26:03 display volumio[1365]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19
Mar 27 11:26:03 display volumio[1365]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected.
Mar 27 11:26:03 display volumio[1365]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers.
Mar 27 11:26:03 display volumio[1365]: Retrying.
Mar 27 11:26:03 display volumio[1365]: --2026-03-27 11:26:03-- (try: 2) http://www.packet.zip/
Mar 27 11:26:03 display volumio[1365]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected.
Mar 27 11:26:03 display volumio[1365]: HTTP request sent, awaiting response... 403 Forbidden
Mar 27 11:26:03 display volumio[1365]: 2026-03-27 11:26:03 ERROR 403: Forbidden.
Mar 27 11:26:03 display volumio[1365]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove 'a': No such file or directory
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove 'packet.zip*': No such file or directory
Mar 27 11:26:03 display volumio[1365]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 27 11:26:03 display volumio[1365]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip*
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove 'a': No such file or directory
Mar 27 11:26:03 display volumio[1365]: /bin/rm: cannot remove 'packet.zip*': No such file or directory
Mar 27 11:26:03 display volumio[1365]: at checkExecSyncError (node:child_process:887:11)
Mar 27 11:26:03 display volumio[1365]: at execSync (node:child_process:959:15)
Mar 27 11:26:03 display volumio[1365]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19
Mar 27 11:26:03 display volumio[1365]: at new Promise ()
Mar 27 11:26:03 display volumio[1365]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12)
Mar 27 11:26:03 display volumio[1365]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32)
Mar 27 11:26:03 display volumio[1365]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42)
Mar 27 11:26:03 display volumio[1365]: at Socket.emit (node:events:514:28)
Mar 27 11:26:03 display volumio[1365]: at /volumio/node_modules/socket.io/lib/socket.js:503:12
Mar 27 11:26:03 display volumio[1365]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) {
Mar 27 11:26:03 display volumio[1365]: status: 1,
Mar 27 11:26:03 display volumio[1365]: signal: null,
Mar 27 11:26:03 display volumio[1365]: output: [
Mar 27 11:26:03 display volumio[1365]: null,
Mar 27 11:26:03 display volumio[1365]: ,
Mar 27 11:26:03 display volumio[1365]:
Mar 27 11:26:03 display volumio[1365]: ],
Mar 27 11:26:03 display volumio[1365]: pid: 5316,
Mar 27 11:26:03 display volumio[1365]: stdout: ,
Mar 27 11:26:03 display volumio[1365]: stderr:
Mar 27 11:26:03 display volumio[1365]: }
Mar 27 11:26:03 display volumio[1365]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 27 11:26:03 display sudo[5333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 11:25'
Mar 27 11:26:03 display sudo[5333]: 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"