Mar 29 12:49:00 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Mar 29 12:49:00 audio-1 volumio[1148]: info: handleBrowseUri took 145 milliseconds Mar 29 12:49:00 audio-1 volumio[1148]: info: Preload queue cleared Mar 29 12:49:00 audio-1 volumio[1148]: info: Preload queue cleared Mar 29 12:49:04 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3325. Mar 29 12:49:04 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:04 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:04 audio-1 upmpdcli[18275]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:49:04 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:49:04 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:49:05 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:49:13 audio-1 volumio[1148]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 12:49:13 audio-1 volumio[1148]: info: Received Get System Version Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:49:13 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:49:13 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:49:13 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:49:13 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:49:13 audio-1 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 29 12:49:14 audio-1 systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 29 12:49:14 audio-1 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 29 12:49:19 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3326. Mar 29 12:49:19 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:19 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:19 audio-1 upmpdcli[18331]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:49:19 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:49:19 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:49:20 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:49:20 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 12:49:34 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3327. Mar 29 12:49:34 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:34 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:34 audio-1 upmpdcli[18347]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:49:34 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:49:34 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:49:44 audio-1 volumio[1148]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/rp2/volumio/bookworm/armhf Mar 29 12:49:44 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:44.820-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:46 audio-1 volumio[1148]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/rp2/volumio/bookworm/armhf Mar 29 12:49:46 audio-1 volumio[1148]: info: Folder /tmp/plugins removed Mar 29 12:49:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:46.583-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:47 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:47.663-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:47 audio-1 volumio[1148]: info: Check plugin dependencies Mar 29 12:49:47 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:49:47 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:47.763-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:47 audio-1 volumio[1148]: info: Checking if plugin already exists Mar 29 12:49:47 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:47.768-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:47 audio-1 volumio[1148]: info: Rename folder Mar 29 12:49:47 audio-1 volumio[1148]: info: Folder /tmp/downloaded_plugin.zip removed Mar 29 12:49:47 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:47.801-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:47 audio-1 volumio[1148]: info: Move to category Mar 29 12:49:49 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3328. Mar 29 12:49:49 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:49 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:49:49 audio-1 upmpdcli[18392]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:49:49 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:49:49 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:49:51 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:51.471-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:51 audio-1 volumio[1148]: info: Checking if install.sh is present Mar 29 12:49:51 audio-1 volumio[1148]: info: Executing install.sh Mar 29 12:49:51 audio-1 sudo[18396]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/rp2/install.sh Mar 29 12:49:51 audio-1 sudo[18396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:49:51 audio-1 volumio[1148]: info: Installing mpv media player... Mar 29 12:49:51 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:51.583-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:51 audio-1 sudo[18398]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Mar 29 12:49:51 audio-1 sudo[18398]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 12:49:52 audio-1 volumio[1148]: info: Get:1 http://archive.raspberrypi.com/debian bookworm InRelease [55.0 kB] Mar 29 12:49:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:52.048-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:52 audio-1 volumio[1148]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease [15.0 kB] Mar 29 12:49:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:52.182-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:52 audio-1 volumio[1148]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf Packages [14.5 MB] Mar 29 12:49:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:52.826-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:49:53 audio-1 volumio[1148]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf Packages [566 kB] Mar 29 12:49:53 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:49:53.047-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:05 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3329. Mar 29 12:50:05 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:05 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:05 audio-1 upmpdcli[18551]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:50:05 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:50:05 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:50:11 audio-1 volumio[1148]: info: Fetched 15.2 MB in 13s (1125 kB/s) Mar 29 12:50:11 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:11.596-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:16 audio-1 volumio[1148]: info: Reading package lists... Mar 29 12:50:16 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:16.675-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:17 audio-1 volumio[1148]: info: Building dependency tree... Mar 29 12:50:17 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:17.595-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:17 audio-1 volumio[1148]: info: Reading state information... Mar 29 12:50:17 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:17.745-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:17 audio-1 sudo[18398]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:17 audio-1 volumio[1148]: info: 41 packages can be upgraded. Run 'apt list --upgradable' to see them. Mar 29 12:50:17 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:17.875-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:18 audio-1 sudo[18582]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y mpv Mar 29 12:50:18 audio-1 sudo[18582]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 29 12:50:20 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3330. Mar 29 12:50:20 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:20 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:20 audio-1 upmpdcli[18585]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:50:20 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:50:20 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:50:22 audio-1 volumio[1148]: info: Reading package lists... Mar 29 12:50:22 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:22.321-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:23 audio-1 volumio[1148]: info: Building dependency tree... Mar 29 12:50:23 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:23.127-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:23 audio-1 volumio[1148]: info: Reading state information... Mar 29 12:50:23 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:23.134-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio[1148]: info: The following additional packages will be installed: Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.714-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio[1148]: info: cpp cpp-12 libdvdnav4 libdvdread8 libegl-mesa0 libegl1 libfile-basedir-perl Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.724-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio[1148]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libfontenc1 libgles2 libice6 Mar 29 12:50:24 audio-1 volumio[1148]: info: libio-stringy-perl libipc-system-simple-perl libisl23 liblua5.2-0 libmpc3 Mar 29 12:50:24 audio-1 volumio[1148]: info: libmpfr6 libmujs2 libnet-dbus-perl libsixel1 libsm6 libtie-ixhash-perl Mar 29 12:50:24 audio-1 volumio[1148]: info: libuchardet0 libva-wayland2 libx11-protocol-perl libxaw7 libxcomposite1 Mar 29 12:50:24 audio-1 volumio[1148]: info: libxft2 libxinerama1 libxkbfile1 libxml-parser-perl libxml-twig-perl Mar 29 12:50:24 audio-1 volumio[1148]: info: libxml-xpathengine-perl libxmu6 libxmuu1 libxpm4 libxpresent1 libxt6 Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.735-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.736-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.739-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.739-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.740-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.740-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio[1148]: info: libxtst6 libxxf86dga1 python3-brotli python3-mutagen python3-pyxattr Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.751-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio[1148]: info: python3-websockets rtmpdump x11-utils x11-xserver-utils xdg-utils yt-dlp Mar 29 12:50:24 audio-1 volumio[1148]: info: zutty Mar 29 12:50:24 audio-1 volumio[1148]: info: Suggested packages: Mar 29 12:50:24 audio-1 volumio[1148]: info: cpp-doc gcc-12-locales cpp-12-doc libdvdcss2 libunicode-map8-perl Mar 29 12:50:24 audio-1 volumio[1148]: info: libunicode-string-perl xml-twig-tools python-mutagen-doc python-pyxattr-doc Mar 29 12:50:24 audio-1 volumio[1148]: info: mesa-utils nickle cairo-5c xorg-docs-core libfribidi-bin | bidiv phantomjs Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.759-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.760-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.763-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.763-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.763-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:24 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:24.764-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.388-04:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.198:57168 @ 0x2c50150" latency=21.485697ms timeout=10s Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.388-04:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.198:57168 @ 0x2c50150" latency=22.476686ms platform=PLATFORM_IOS version=5.260209.0 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.388-04:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" Mar 29 12:50:25 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:25 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:25 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.392-04:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" name="Audio 1" Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.578-04:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" language=en Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.581-04:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" timezone=America/New_York Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.583-04:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" available=true connected=true macAddress=b8:27:eb:7e:10:22 ip4Address=192.168.1.24/24 ip6Address= Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.585-04:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.586-04:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" setupComplete=true Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 12:50:25 audio-1 volumio[1148]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 29 12:50:25 audio-1 volumio[1148]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 29 12:50:25 audio-1 volumio[1148]: Card sysdefault:1 'Headphones'/'bcm2835 Headphones' Mar 29 12:50:25 audio-1 volumio[1148]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 2 Mar 29 12:50:25 audio-1 volumio[1148]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 12:50:25 audio-1 volumio[1148]: {"cmd":"/usr/local/bin/alsacap -C 2","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 2\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 12:50:25 audio-1 volumio[1148]: amixer -c 2 info | grep "vc4-hdmi" Mar 29 12:50:25 audio-1 volumio[1148]: Card sysdefault:2 'vc4hdmi'/'vc4-hdmi' Mar 29 12:50:25 audio-1 volumio[1148]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Mar 29 12:50:25 audio-1 volumio[1148]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 12:50:25 audio-1 volumio[1148]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 12:50:25 audio-1 volumio[1148]: amixer -c 3 info | grep "Allo DigiOne" Mar 29 12:50:25 audio-1 volumio[1148]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 3 Mar 29 12:50:25 audio-1 volumio[1148]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 29 12:50:25 audio-1 volumio[1148]: {"cmd":"/usr/local/bin/alsacap -C 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 29 12:50:25 audio-1 volumio[1148]: amixer -c 3 info | grep "snd_allo_digione" Mar 29 12:50:25 audio-1 volumio[1148]: Card sysdefault:3 'sndallodigione'/'snd_allo_digione' Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.812-04:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" selectedOutputId=3 Mar 29 12:50:25 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:25 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:25 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.833-04:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" currentVersion=4.119 latestVersion=4.119 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.833-04:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.198:57168 @ 0x2c50150" status=UPDATE_STATUS_NONE progress=0 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.833-04:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" userId= Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.834-04:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" providers=9 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.834-04:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" plugins=62 Mar 29 12:50:25 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:25 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.838-04:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.839-04:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.198:57168 @ 0x2c50150" id= title= Mar 29 12:50:25 audio-1 volumio[1148]: info: The following NEW packages will be installed: Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.866-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: cpp cpp-12 libdvdnav4 libdvdread8 libegl-mesa0 libegl1 libfile-basedir-perl Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.875-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: libfile-desktopentry-perl libfile-mimeinfo-perl libfontenc1 libgles2 libice6 Mar 29 12:50:25 audio-1 volumio[1148]: info: libio-stringy-perl libipc-system-simple-perl libisl23 liblua5.2-0 libmpc3 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.880-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.880-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: libmpfr6 libmujs2 libnet-dbus-perl libsixel1 libsm6 libtie-ixhash-perl Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.892-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: libuchardet0 libva-wayland2 libx11-protocol-perl libxaw7 libxcomposite1 Mar 29 12:50:25 audio-1 volumio[1148]: info: libxft2 libxinerama1 libxkbfile1 libxml-parser-perl libxml-twig-perl Mar 29 12:50:25 audio-1 volumio[1148]: info: libxml-xpathengine-perl libxmu6 libxmuu1 libxpm4 libxpresent1 libxt6 Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.901-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.902-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.903-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: libxtst6 libxxf86dga1 mpv python3-brotli python3-mutagen python3-pyxattr Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.915-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: python3-websockets rtmpdump x11-utils x11-xserver-utils xdg-utils yt-dlp Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.922-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:25 audio-1 volumio[1148]: info: zutty Mar 29 12:50:25 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:25.926-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 sudo[18621]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 12:50:26 audio-1 sudo[18621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:26 audio-1 sudo[18621]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:26 audio-1 sudo[18623]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 12:50:26 audio-1 sudo[18623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:26 audio-1 sudo[18623]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:26 audio-1 volumio[1148]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.198 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:50:26 audio-1 volumio[1148]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 12:50:26 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:26 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: Listing playlists Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 12:50:26 audio-1 volumio[1148]: info: 0 upgraded, 52 newly installed, 0 to remove and 41 not upgraded. Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.339-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 volumio[1148]: info: Need to get 14.5 MB of archives. Mar 29 12:50:26 audio-1 volumio[1148]: info: After this operation, 50.9 MB of additional disk space will be used. Mar 29 12:50:26 audio-1 volumio[1148]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf libegl-mesa0 armhf 24.2.8-1~bpo12+rpt4 [141 kB] Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.343-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.344-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.345-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 sudo[18628]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 12:50:26 audio-1 sudo[18628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:26 audio-1 sudo[18628]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:26 audio-1 sudo[18629]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 12:50:26 audio-1 sudo[18629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:26 audio-1 sudo[18629]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:26 audio-1 volumio[1148]: verbose: New Socket.io Connection to 192.168.1.24 from 192.168.1.198 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 12:50:26 audio-1 volumio[1148]: info: Get:2 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libisl23 armhf 0.25-1.1 [517 kB] Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.669-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 12:50:26 audio-1 volumio[1148]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 29 12:50:26 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:26 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:26 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:26 audio-1 volumio[1148]: info: Listing playlists Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 12:50:26 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:50:26 audio-1 volumio[1148]: info: Get:3 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libmpfr6 armhf 4.2.0-1 [574 kB] Mar 29 12:50:26 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:26.917-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:27 audio-1 volumio[1148]: info: Get:4 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libmpc3 armhf 1.3.1-1 [41.0 kB] Mar 29 12:50:27 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:27.133-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:27 audio-1 volumio[1148]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf cpp-12 armhf 12.2.0-14+rpi1+deb12u1 [7159 kB] Mar 29 12:50:27 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:27.424-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:50:28 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:28 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:28 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:50:28 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:28 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:28 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetQueue Mar 29 12:50:28 audio-1 volumio[1148]: info: CoreStateMachine::getQueue Mar 29 12:50:28 audio-1 volumio[1148]: info: CorePlayQueue::getQueue Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:6 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf cpp armhf 4:12.2.0-3+rpi1 [7048 B] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.564-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:7 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libdvdread8 armhf 6.1.3-1 [87.4 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.608-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:8 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libdvdnav4 armhf 6.1.1-1 [43.3 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.686-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:9 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libegl1 armhf 1.6.0-1 [28.3 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.695-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:10 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libipc-system-simple-perl all 1.30-2 [26.8 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.705-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:11 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libfile-basedir-perl all 0.09-2 [15.1 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.708-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:12 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libfile-desktopentry-perl all 0.22-3 [17.6 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.715-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:29 audio-1 volumio[1148]: info: Get:13 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libfile-mimeinfo-perl all 0.33-1 [48.3 kB] Mar 29 12:50:29 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:29.784-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:30 audio-1 volumio[1148]: info: Get:14 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libfontenc1 armhf 1:1.1.4-1 [22.3 kB] Mar 29 12:50:30 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:30.075-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:30 audio-1 volumio[1148]: info: Get:15 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libgles2 armhf 1.6.0-1 [17.2 kB] Mar 29 12:50:30 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:30.413-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:30 audio-1 volumio[1148]: info: Get:16 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libice6 armhf 2:1.0.10-1 [51.7 kB] Mar 29 12:50:30 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:30.657-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:30 audio-1 volumio[1148]: info: Get:17 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libio-stringy-perl all 2.111-3 [56.5 kB] Mar 29 12:50:30 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:30.948-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:31 audio-1 volumio[1148]: info: Get:18 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf liblua5.2-0 armhf 5.2.4-3 [85.7 kB] Mar 29 12:50:31 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:31.229-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:31 audio-1 volumio[1148]: info: Get:19 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libmujs2 armhf 1.3.2-1 [90.7 kB] Mar 29 12:50:31 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:31.526-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:32 audio-1 volumio[1148]: info: Get:20 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxml-parser-perl armhf 2.46-4+deb12u1 [197 kB] Mar 29 12:50:32 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:32.075-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:32 audio-1 volumio[1148]: info: Get:21 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxml-twig-perl all 1:3.52-2 [181 kB] Mar 29 12:50:32 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:32.238-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:32 audio-1 volumio[1148]: info: Get:22 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libnet-dbus-perl armhf 1.2.0-2 [174 kB] Mar 29 12:50:32 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:32.515-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:32 audio-1 volumio[1148]: info: Get:23 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libsixel1 armhf 1.10.3-3 [81.1 kB] Mar 29 12:50:32 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:32.800-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:33 audio-1 volumio[1148]: info: Get:24 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libsm6 armhf 2:1.2.3-1 [32.9 kB] Mar 29 12:50:33 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:33.093-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:33 audio-1 volumio[1148]: info: Get:25 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libtie-ixhash-perl all 1.23-4 [11.7 kB] Mar 29 12:50:33 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:33.384-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:33 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 12:50:33 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 12:50:33 audio-1 volumio[1148]: info: Get:26 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libuchardet0 armhf 0.0.7-1 [65.0 kB] Mar 29 12:50:33 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:33.671-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:33 audio-1 volumio[1148]: info: Get:27 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libva-wayland2 armhf 2.17.0-1 [17.1 kB] Mar 29 12:50:33 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:33.967-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:34 audio-1 volumio[1148]: info: Get:28 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libx11-protocol-perl all 0.56-9 [120 kB] Mar 29 12:50:34 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:34.270-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:34 audio-1 volumio[1148]: info: Get:29 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxt6 armhf 1:1.2.1-1.1 [155 kB] Mar 29 12:50:34 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:34.623-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:34 audio-1 volumio[1148]: info: Get:30 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxmu6 armhf 2:1.1.3-3 [52.0 kB] Mar 29 12:50:34 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:34.837-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:35 audio-1 volumio[1148]: info: Get:31 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxpm4 armhf 1:3.5.12-1.1+deb12u1 [37.7 kB] Mar 29 12:50:35 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:35.124-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:35 audio-1 volumio[1148]: info: Get:32 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxaw7 armhf 2:1.0.14-1 [165 kB] Mar 29 12:50:35 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:35.410-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:35 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3331. Mar 29 12:50:35 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:35 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:35 audio-1 upmpdcli[18649]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:50:35 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:50:35 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:50:35 audio-1 volumio[1148]: info: Get:33 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxcomposite1 armhf 1:0.4.5-1 [16.2 kB] Mar 29 12:50:35 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:35.702-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:35 audio-1 volumio[1148]: info: Get:34 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxft2 armhf 2.3.6-1 [53.7 kB] Mar 29 12:50:35 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:35.995-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:36 audio-1 volumio[1148]: info: Get:35 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxinerama1 armhf 2:1.1.4-3 [17.4 kB] Mar 29 12:50:36 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:36.290-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:36 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 12:50:36 audio-1 volumio[1148]: info: Get:36 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxkbfile1 armhf 1:1.1.0-1 [66.4 kB] Mar 29 12:50:36 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:36.607-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:36 audio-1 volumio[1148]: info: Get:37 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxml-xpathengine-perl all 0.14-2 [33.5 kB] Mar 29 12:50:36 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:36.867-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:37 audio-1 volumio[1148]: info: Get:38 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxmuu1 armhf 2:1.1.3-3 [23.3 kB] Mar 29 12:50:37 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:37.158-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:37 audio-1 volumio[1148]: info: Get:39 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxpresent1 armhf 1.0.0-2 [7316 B] Mar 29 12:50:37 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:37.439-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:37 audio-1 volumio[1148]: info: Get:40 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxtst6 armhf 2:1.2.3-1.1 [26.3 kB] Mar 29 12:50:37 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:37.726-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:38 audio-1 volumio[1148]: info: Get:41 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf libxxf86dga1 armhf 2:1.1.5-1 [20.9 kB] Mar 29 12:50:38 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:38.011-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:38 audio-1 volumio[1148]: info: Get:42 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf mpv armhf 0.35.1-4 [1042 kB] Mar 29 12:50:38 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:38.306-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:38 audio-1 volumio[1148]: info: Enabling plugin rp2 Mar 29 12:50:38 audio-1 volumio[1148]: info: Loading plugin "rp2"... Mar 29 12:50:41 audio-1 volumio[1148]: info: PLUGIN START: rp2 Mar 29 12:50:41 audio-1 volumio[1148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:50:41 audio-1 volumio[1148]: info: [1774803041090] CoreMusicLibrary::Adding element Radio Paradise (RP2) Mar 29 12:50:41 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:41 audio-1 volumio[1148]: Cannot find translation for source QOBUZ Mar 29 12:50:41 audio-1 volumio[1148]: Cannot find translation for source Radio Paradise (RP2) Mar 29 12:50:41 audio-1 volumio[1148]: info: Done. Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:43 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-brotli armhf 1.0.9-2+b3 [278 kB] Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.114-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:44 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf python3-mutagen all 1.46.0-1 [135 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:45 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf python3-pyxattr armhf 0.8.1-1 [15.8 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:46 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf python3-websockets all 10.4-1 [62.8 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:47 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf rtmpdump armhf 2.4+20151223.gitfa8646d.1-2+b2 [47.7 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:48 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf x11-utils armhf 7.7+5 [176 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:49 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf x11-xserver-utils armhf 7.7+9 [151 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:50 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf xdg-utils all 1.1.3-4.1 [75.5 kB] Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:51 http://mirror.umd.edu/raspbian/raspbian bookworm/main armhf yt-dlp all 2023.03.04-1 [1778 kB] Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.131-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.132-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.134-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.135-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.135-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.137-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.138-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.139-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:41 audio-1 volumio[1148]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesRadio Paradise (RP2) Mar 29 12:50:41 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:41 audio-1 volumio[1148]: Cannot find translation for source QOBUZ Mar 29 12:50:41 audio-1 volumio[1148]: info: Disabling plugin rp2 Mar 29 12:50:41 audio-1 volumio[1148]: info: Done. Mar 29 12:50:41 audio-1 volumio[1148]: info: Get:52 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf zutty armhf 0.14.0.20230218+dfsg1-1 [155 kB] Mar 29 12:50:41 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:41.965-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:42 audio-1 volumio[1148]: info: Tunnel connection is inactive, restarting it Mar 29 12:50:42 audio-1 volumio[1148]: info: Starting Tunnel 1 Mar 29 12:50:42 audio-1 volumio[1148]: info: Starting Tunnel Connection Checker Mar 29 12:50:42 audio-1 sudo[18673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 29 12:50:42 audio-1 sudo[18673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 29 12:50:42 audio-1 autossh[18123]: received signal to exit (15) Mar 29 12:50:42 audio-1 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Mar 29 12:50:42 audio-1 systemd[1]: sshtunnel.service: Deactivated successfully. Mar 29 12:50:42 audio-1 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Mar 29 12:50:42 audio-1 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Mar 29 12:50:42 audio-1 sudo[18673]: pam_unix(sudo:session): session closed for user root Mar 29 12:50:42 audio-1 volumio[1148]: info: Remote SSH Started Mar 29 12:50:42 audio-1 volumio[1148]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 29 12:50:42 audio-1 autossh[18676]: port set to 0, monitoring disabled Mar 29 12:50:42 audio-1 autossh[18676]: starting ssh (count 1) Mar 29 12:50:42 audio-1 autossh[18676]: ssh child pid is 18679 Mar 29 12:50:42 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:42 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:42 audio-1 volumiossh-tunnel[18679]: Warning: Permanently added '[us4.myvolumio.org]:2222' (ED25519) to the list of known hosts. Mar 29 12:50:43 audio-1 volumio[1148]: info: Enabling plugin rp2 Mar 29 12:50:43 audio-1 volumio[1148]: info: Loading plugin "rp2"... Mar 29 12:50:43 audio-1 volumio[1148]: info: PLUGIN START: rp2 Mar 29 12:50:43 audio-1 volumio[1148]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 12:50:43 audio-1 volumio[1148]: info: [1774803043139] CoreMusicLibrary::Adding element Radio Paradise (RP2) Mar 29 12:50:43 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:43 audio-1 volumio[1148]: Cannot find translation for source QOBUZ Mar 29 12:50:43 audio-1 volumio[1148]: Cannot find translation for source Radio Paradise (RP2) Mar 29 12:50:43 audio-1 volumio[1148]: info: Done. Mar 29 12:50:43 audio-1 volumio[1148]: info: Fetched 14.5 MB in 16s (905 kB/s) Mar 29 12:50:43 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:43.824-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:43 audio-1 volumio[1148]: info: Selecting previously unselected package libisl23:armhf. Mar 29 12:50:43 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:43.987-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 12:50:44 audio-1 volumio[1148]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 12:50:44 audio-1 volumio[1148]: info: Received Get System Version Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 12:50:44 audio-1 volumio[1148]: info: Received Get System Info Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 12:50:44 audio-1 volumio[1148]: info: Discovery: Getting this device information Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetState Mar 29 12:50:44 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:44 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 12:50:45 audio-1 volumio[1148]: [620B blob data] Mar 29 12:50:45 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:45.590-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:45 audio-1 volumio[1148]: info: Preparing to unpack .../00-libisl23_0.25-1.1_armhf.deb ... Mar 29 12:50:45 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:45.609-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:45 audio-1 volumio[1148]: info: Unpacking libisl23:armhf (0.25-1.1) ... Mar 29 12:50:45 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:45.635-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Selecting previously unselected package libmpfr6:armhf. Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.095-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Preparing to unpack .../01-libmpfr6_4.2.0-1_armhf.deb ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.107-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Unpacking libmpfr6:armhf (4.2.0-1) ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.128-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Selecting previously unselected package libmpc3:armhf. Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.349-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Preparing to unpack .../02-libmpc3_1.3.1-1_armhf.deb ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.362-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Unpacking libmpc3:armhf (1.3.1-1) ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.378-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Selecting previously unselected package cpp-12. Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.518-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Preparing to unpack .../03-cpp-12_12.2.0-14+rpi1+deb12u1_armhf.deb ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.532-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:46 audio-1 volumio[1148]: info: Unpacking cpp-12 (12.2.0-14+rpi1+deb12u1) ... Mar 29 12:50:46 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:46.544-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:49 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: rp2 , handleBrowseUri Mar 29 12:50:50 audio-1 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3332. Mar 29 12:50:50 audio-1 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:50 audio-1 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Mar 29 12:50:50 audio-1 upmpdcli[18742]: Could not open config: /tmp/upmpdcli.conf Mar 29 12:50:50 audio-1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 29 12:50:50 audio-1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 29 12:50:51 audio-1 volumio[1148]: info: Selecting previously unselected package cpp. Mar 29 12:50:51 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:51.336-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:51 audio-1 volumio[1148]: info: Preparing to unpack .../04-cpp_4%3a12.2.0-3+rpi1_armhf.deb ... Mar 29 12:50:51 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:51.346-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:51 audio-1 volumio[1148]: info: Unpacking cpp (4:12.2.0-3+rpi1) ... Mar 29 12:50:51 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:51.363-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:51 audio-1 volumio[1148]: info: Preload queue cleared Mar 29 12:50:52 audio-1 volumio[1148]: info: Selecting previously unselected package libdvdread8:armhf. Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.070-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Preparing to unpack .../05-libdvdread8_6.1.3-1_armhf.deb ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.096-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Unpacking libdvdread8:armhf (6.1.3-1) ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.124-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Selecting previously unselected package libdvdnav4:armhf. Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.401-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Preparing to unpack .../06-libdvdnav4_6.1.1-1_armhf.deb ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.417-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Unpacking libdvdnav4:armhf (6.1.1-1) ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.438-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Selecting previously unselected package libegl-mesa0:armhf. Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.679-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Preparing to unpack .../07-libegl-mesa0_24.2.8-1~bpo12+rpt4_armhf.deb ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.694-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Unpacking libegl-mesa0:armhf (24.2.8-1~bpo12+rpt4) ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.750-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Selecting previously unselected package libegl1:armhf. Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.949-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Preparing to unpack .../08-libegl1_1.6.0-1_armhf.deb ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.960-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:52 audio-1 volumio[1148]: info: Unpacking libegl1:armhf (1.6.0-1) ... Mar 29 12:50:52 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:52.974-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:53 audio-1 volumio[1148]: info: Selecting previously unselected package libipc-system-simple-perl. Mar 29 12:50:53 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:53.139-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:53 audio-1 volumio[1148]: info: Preparing to unpack .../09-libipc-system-simple-perl_1.30-2_all.deb ... Mar 29 12:50:53 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:53.157-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:53 audio-1 volumio[1148]: info: Unpacking libipc-system-simple-perl (1.30-2) ... Mar 29 12:50:53 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:53.171-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Selecting previously unselected package libfile-basedir-perl. Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.090-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Preparing to unpack .../10-libfile-basedir-perl_0.09-2_all.deb ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.107-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Unpacking libfile-basedir-perl (0.09-2) ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.121-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Selecting previously unselected package libfile-desktopentry-perl. Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.294-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Preparing to unpack .../11-libfile-desktopentry-perl_0.22-3_all.deb ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.307-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Unpacking libfile-desktopentry-perl (0.22-3) ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.326-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Selecting previously unselected package libfile-mimeinfo-perl. Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.494-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Preparing to unpack .../12-libfile-mimeinfo-perl_0.33-1_all.deb ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.508-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Unpacking libfile-mimeinfo-perl (0.33-1) ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.528-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Selecting previously unselected package libfontenc1:armhf. Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.869-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Preparing to unpack .../13-libfontenc1_1%3a1.1.4-1_armhf.deb ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.885-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:54 audio-1 volumio[1148]: info: Unpacking libfontenc1:armhf (1:1.1.4-1) ... Mar 29 12:50:54 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:54.902-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Selecting previously unselected package libgles2:armhf. Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.085-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Preparing to unpack .../14-libgles2_1.6.0-1_armhf.deb ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.099-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Unpacking libgles2:armhf (1.6.0-1) ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.113-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Selecting previously unselected package libice6:armhf. Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.284-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Preparing to unpack .../15-libice6_2%3a1.0.10-1_armhf.deb ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.298-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Unpacking libice6:armhf (2:1.0.10-1) ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.310-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Selecting previously unselected package libio-stringy-perl. Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.444-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Preparing to unpack .../16-libio-stringy-perl_2.111-3_all.deb ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.457-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Unpacking libio-stringy-perl (2.111-3) ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.489-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Selecting previously unselected package liblua5.2-0:armhf. Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.748-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Preparing to unpack .../17-liblua5.2-0_5.2.4-3_armhf.deb ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.756-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:55 audio-1 volumio[1148]: info: Unpacking liblua5.2-0:armhf (5.2.4-3) ... Mar 29 12:50:55 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:55.784-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Selecting previously unselected package libmujs2:armhf. Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.001-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Preparing to unpack .../18-libmujs2_1.3.2-1_armhf.deb ... Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.014-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Unpacking libmujs2:armhf (1.3.2-1) ... Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.039-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Selecting previously unselected package libxml-parser-perl. Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.233-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Preparing to unpack .../19-libxml-parser-perl_2.46-4+deb12u1_armhf.deb ... Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.247-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Unpacking libxml-parser-perl (2.46-4+deb12u1) ... Mar 29 12:50:56 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:56.258-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:56 audio-1 volumio[1148]: info: Preload queue cleared Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::ClearQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::stop Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::clearPlayQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::saveQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::addQueueItems Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::addQueueItems Mar 29 12:50:56 audio-1 volumio[1148]: info: Preload queue cleared Mar 29 12:50:56 audio-1 volumio[1148]: info: Adding Item to queue: rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D3%22%2C%22name%22%3A%22The%20Globe%22%2C%22title%22%3A%22The%20Globe%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F3%2Fcover_512x512%2F0.jpg%22%7D Mar 29 12:50:56 audio-1 volumio[1148]: info: Exploding uri rp2/channel@qi=%7B%22service%22%3A%22rp2%22%2C%22uri%22%3A%22rp2%2Fchannel%40id%3D3%22%2C%22name%22%3A%22The%20Globe%22%2C%22title%22%3A%22The%20Globe%22%2C%22artist%22%3A%22Radio%20Paradise%22%2C%22albumart%22%3A%22https%3A%2F%2Fimg.radioparadise.com%2Fchannels%2F0%2F3%2Fcover_512x512%2F0.jpg%22%7D in service rp2 Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::volumioPushQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::saveQueue Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::updateTrackBlock Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::getTrackBlock Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::volumioPlay Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::play index 0 Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::stop Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::play index undefined Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreStateMachine::startPlaybackTimer Mar 29 12:50:56 audio-1 volumio[1148]: info: CorePlayQueue::getTrack 0 Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 12:50:56 audio-1 volumio[1148]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 29 12:50:56 audio-1 volumio[1148]: info: [rp2] clearAddPlayTrack: rp2/channel@id=3 Mar 29 12:50:56 audio-1 volumio[1148]: verbose: [rp2] API: https://api.radioparadise.com/api/play?source=24&event=0&elapsed=0&bitrate=4&action=start&player_id=********&info=true&chan=3&audio_type= Mar 29 12:50:57 audio-1 volumio[1148]: info: Selecting previously unselected package libxml-twig-perl. Mar 29 12:50:57 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:57.142-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:57 audio-1 volumio[1148]: info: Preparing to unpack .../20-libxml-twig-perl_1%3a3.52-2_all.deb ... Mar 29 12:50:57 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:57.150-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:57 audio-1 volumio[1148]: info: Unpacking libxml-twig-perl (1:3.52-2) ... Mar 29 12:50:57 audio-1 volumio5-onboarding[1545]: time=2026-03-29T12:50:57.170-04:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Obtained block for channel "3" Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] ------------- Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Block summary Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] ------------- Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Stream URL: https://audio-geo.radioparadise.com/chan/3/x/1105/4/b/1105-0.flac Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Tracks: Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 0. El Barrio (5:37 | elapsed: 0s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 1. Tujunga (3:15 | elapsed: 5m 37s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 2. Nkwanta Bisa (At The Junction) (3:15 | elapsed: 8m 52s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 3. Santa Maria (feat. Khalil Mounji & Nabila Dali) (3:07 | elapsed: 12m 7s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 4. Ritmo de Cumbia (3:10 | elapsed: 15m 15s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] 5. Lautlos (5:47 | elapsed: 18m 25s) Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Mar 29 12:50:57 audio-1 volumio[1148]: verbose: [rp2] Current track scheduled playback vs. current time: 3/29/2026, 12:48:46 PM <-> 3/29/2026, 12:50:57 PM Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Going to start playback of current track at 2:10 Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] Starting mpv Mar 29 12:50:57 audio-1 volumio[1148]: /bin/sh: 1: mpv: not found Mar 29 12:50:57 audio-1 volumio[1148]: error: [rp2] [mpv] Failed to get mpv version: Command failed: mpv --version Mar 29 12:50:57 audio-1 volumio[1148]: /bin/sh: 1: mpv: not found Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] [mpv] mpv process spawned Mar 29 12:50:57 audio-1 volumio[1148]: verbose: [rp2] Waiting for player event "playing"... Mar 29 12:50:57 audio-1 volumio[1148]: info: [rp2] [mpv] (PID: 18938) /bin/sh: 1: mpv: not found Mar 29 12:50:57 audio-1 volumio[1148]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:50:57 audio-1 volumio[1148]: TypeError: Cannot read properties of null (reading 'reset') Mar 29 12:50:57 audio-1 volumio[1148]: at /data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:207:48 Mar 29 12:50:57 audio-1 volumio[1148]: at ChildProcess. (/data/plugins/music_service/rp2/node_modules/volumio-ext-players/dist/mpv/MPVService.js:221:19) Mar 29 12:50:57 audio-1 volumio[1148]: at ChildProcess.emit (node:events:514:28) Mar 29 12:50:57 audio-1 volumio[1148]: at maybeClose (node:internal/child_process:1105:16) Mar 29 12:50:57 audio-1 volumio[1148]: at Socket. (node:internal/child_process:457:11) Mar 29 12:50:57 audio-1 volumio[1148]: at Socket.emit (node:events:514:28) Mar 29 12:50:57 audio-1 volumio[1148]: at Pipe. (node:net:337:12) Mar 29 12:50:57 audio-1 volumio[1148]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 12:50:58 audio-1 sudo[18982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-29 12:49' Mar 29 12:50:58 audio-1 sudo[18982]: 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"