Aug 25 13:58:06 jessroom volumio[1244]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Aug 25 13:58:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:06.568Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:07 jessroom volumio[1244]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/bookworm/armhf Aug 25 13:58:07 jessroom volumio[1244]: info: Folder /tmp/plugins removed Aug 25 13:58:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:07.796Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:08 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:08.270Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:08 jessroom volumio[1244]: info: Check plugin dependencies Aug 25 13:58:08 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 13:58:08 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:08.343Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:08 jessroom volumio[1244]: info: Checking if plugin already exists Aug 25 13:58:08 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:08.347Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:08 jessroom volumio[1244]: info: Rename folder Aug 25 13:58:08 jessroom volumio[1244]: info: Folder /tmp/downloaded_plugin.zip removed Aug 25 13:58:08 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:08.372Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:08 jessroom volumio[1244]: info: Move to category Aug 25 13:58:09 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:09.924Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:09 jessroom volumio[1244]: info: Checking if install.sh is present Aug 25 13:58:09 jessroom volumio[1244]: info: Executing install.sh Aug 25 13:58:10 jessroom sudo[3708]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Aug 25 13:58:10 jessroom sudo[3708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:58:10 jessroom volumio[1244]: info: Installing Go-librespot Aug 25 13:58:10 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:10.021Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:10 jessroom volumio[1244]: info: Checking old vollibrespot installs Aug 25 13:58:10 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:10.031Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:10 jessroom systemd[1]: Reloading. Aug 25 13:58:11 jessroom volumio[1244]: info: Downloading daemon Aug 25 13:58:11 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:11.038Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:12 jessroom volumio[1244]: info: Creating directories Aug 25 13:58:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:12.257Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:12 jessroom volumio[1244]: info: Creating data directory Aug 25 13:58:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:12.274Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:12 jessroom volumio[1244]: info: Creating Start Script Aug 25 13:58:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:12.281Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:12 jessroom systemd[1]: Reloading. Aug 25 13:58:13 jessroom sudo[3708]: pam_unix(sudo:session): session closed for user root Aug 25 13:58:13 jessroom volumio[1244]: Plugin install end detected on script Aug 25 13:58:13 jessroom volumio[1244]: info: Install script completed Aug 25 13:58:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:13.164Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:13 jessroom volumio[1244]: info: Adding reference to registry Aug 25 13:58:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:13.169Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:13 jessroom volumio[1244]: info: Done installing plugin. Aug 25 13:58:13 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:58:13 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 25 13:58:13 jessroom volumio[1244]: info: Folder /tmp/plugins removed Aug 25 13:58:13 jessroom volumio[1244]: info: Folder /tmp/downloaded_plugin.zip removed Aug 25 13:58:13 jessroom volumio[1244]: info: Folder /data/temp removed Aug 25 13:58:14 jessroom volumio[1244]: info: Enabling plugin spop Aug 25 13:58:14 jessroom volumio[1244]: info: Loading plugin "spop"... Aug 25 13:58:14 jessroom volumio[1244]: info: PLUGIN START: spop Aug 25 13:58:14 jessroom volumio[1244]: info: Creating Spotify config file Aug 25 13:58:14 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 13:58:14 jessroom volumio[1244]: info: Done. Aug 25 13:58:14 jessroom volumio[1244]: info: Spotify config file written Aug 25 13:58:14 jessroom volumio[1244]: info: No need to fix Spotify hosts Aug 25 13:58:14 jessroom sudo[3778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Aug 25 13:58:14 jessroom sudo[3778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:58:14 jessroom systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 25 13:58:14 jessroom systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 25 13:58:15 jessroom systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Aug 25 13:58:15 jessroom go-librespot[3780]: go-librespot daemon starting... Aug 25 13:58:15 jessroom sudo[3778]: pam_unix(sudo:session): session closed for user root Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=info msg="running go-librespot 0.7.1" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=debug msg="no app state found" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=debug msg="stored credentials not found" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=info msg="generated new device id: 2eed0598d9a5d1db3062e18a3da9ad6c5579568a" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=info msg="api server listening on 127.0.0.1:9879" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=info msg="zeroconf server listening on port 39195" Aug 25 13:58:15 jessroom go-librespot[3781]: time="2026-08-25T13:58:15-05:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Aug 25 13:58:18 jessroom volumio[1244]: info: go-librespot daemon successfully initialized Aug 25 13:58:21 jessroom volumio[1244]: info: Initializing connection to go-librespot Websocket Aug 25 13:58:21 jessroom go-librespot[3781]: time="2026-08-25T13:58:21-05:00" level=debug msg="new websocket client" Aug 25 13:58:21 jessroom volumio[1244]: info: Connection to go-librespot Websocket established Aug 25 13:58:23 jessroom volumio[1244]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/bookworm/armhf Aug 25 13:58:23 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:23.514Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:24 jessroom volumio[1244]: info: Getting Spotify volume Aug 25 13:58:24 jessroom volumio[1244]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 25 13:58:24 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:58:24 jessroom volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 25 13:58:24 jessroom volumio[1244]: info: Aligning Spotify Volume to Volumio Volume Aug 25 13:58:24 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:58:24 jessroom volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 25 13:58:24 jessroom volumio[1244]: info: Setting Spotify Volume from Volumio: 86 Aug 25 13:58:24 jessroom nmbd[2469]: [2026/08/25 13:58:24.448537, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Aug 25 13:58:24 jessroom nmbd[2469]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.25 for name WORKGROUP<1d>. Aug 25 13:58:24 jessroom nmbd[2469]: This response was from IP 192.168.1.8, reporting an IP address of 192.168.1.8. Aug 25 13:58:24 jessroom volumio[1244]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/bookworm/armhf Aug 25 13:58:24 jessroom volumio[1244]: info: Folder /tmp/plugins removed Aug 25 13:58:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:24.666Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:25.162Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:25 jessroom volumio[1244]: info: Check plugin dependencies Aug 25 13:58:25 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 13:58:25 jessroom volumio[1244]: info: Checking if plugin already exists Aug 25 13:58:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:25.210Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:25.210Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:25 jessroom volumio[1244]: info: Rename folder Aug 25 13:58:25 jessroom volumio[1244]: info: Folder /tmp/downloaded_plugin.zip removed Aug 25 13:58:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:25.227Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:25 jessroom volumio[1244]: info: Move to category Aug 25 13:58:25 jessroom volumio[1244]: info: Sending Spotify command with payload to local API: /player/volume Aug 25 13:58:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:27.892Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:27 jessroom volumio[1244]: info: Checking if install.sh is present Aug 25 13:58:27 jessroom volumio[1244]: info: Executing install.sh Aug 25 13:58:27 jessroom sudo[3823]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/soundcloud/install.sh Aug 25 13:58:27 jessroom sudo[3823]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 25 13:58:27 jessroom volumio[1244]: info: Installing VLC media player... Aug 25 13:58:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:27.929Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:27 jessroom sudo[3825]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Aug 25 13:58:27 jessroom sudo[3825]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 25 13:58:28 jessroom volumio[1244]: info: Hit:1 http://archive.raspberrypi.com/debian bookworm InRelease Aug 25 13:58:28 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:28.364Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:28 jessroom volumio[1244]: info: Hit:2 http://raspbian.raspberrypi.com/raspbian bookworm InRelease Aug 25 13:58:28 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:28.377Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:32 jessroom volumio[1244]: info: Reading package lists... Aug 25 13:58:32 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:32.640Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:33 jessroom volumio[1244]: info: Building dependency tree... Aug 25 13:58:33 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:33.327Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:35 jessroom volumio[1244]: info: Reading state information... Aug 25 13:58:35 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:35.026Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:35 jessroom volumio[1244]: info: 93 packages can be upgraded. Run 'apt list --upgradable' to see them. Aug 25 13:58:35 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:35.106Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:35 jessroom sudo[3825]: pam_unix(sudo:session): session closed for user root Aug 25 13:58:35 jessroom sudo[3978]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y vlc-bin vlc-plugin-base Aug 25 13:58:35 jessroom sudo[3978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 25 13:58:36 jessroom volumio[1244]: info: Reading package lists... Aug 25 13:58:36 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:36.367Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:36 jessroom volumio[1244]: info: Building dependency tree... Aug 25 13:58:36 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:36.862Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:36 jessroom volumio[1244]: info: Reading state information... Aug 25 13:58:36 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:36.872Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: The following additional packages will be installed: Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.805Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: liba52-0.7.4 libaribb24-0 libbasicusageenvironment2 libcddb2 libdca0 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.815Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: libdvbpsi10 libdvdnav4 libdvdread8 libebml5 libgroupsock30 libidn12 libkate1 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.824Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: liblirc-client0 liblivemedia116 liblua5.2-0 libmatroska7 libmpeg2-4 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.834Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: libmtp-common libmtp-runtime libmtp9 libopenmpt-modplug1 libprotobuf-lite32 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.842Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: libproxy-tools libproxy1v5 libresid-builder0c2a libsdl-image1.2 Aug 25 13:58:37 jessroom volumio[1244]: info: libsdl1.2debian libsecret-1-0 libsecret-common libsidplay2 libspatialaudio0 Aug 25 13:58:37 jessroom volumio[1244]: info: libtag1v5 libtag1v5-vanilla libusageenvironment3 libvlc-bin libvlc5 Aug 25 13:58:37 jessroom volumio[1244]: info: libvlccore9 libxcb-keysyms1 vlc-data Aug 25 13:58:37 jessroom volumio[1244]: info: Suggested packages: Aug 25 13:58:37 jessroom volumio[1244]: info: libdvdcss2 lirc Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.853Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.853Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.854Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.855Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.855Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.856Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: The following NEW packages will be installed: Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.917Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: liba52-0.7.4 libaribb24-0 libbasicusageenvironment2 libcddb2 libdca0 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.936Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: libdvbpsi10 libdvdnav4 libdvdread8 libebml5 libgroupsock30 libidn12 libkate1 Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.947Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio[1244]: info: liblirc-client0 liblivemedia116 liblua5.2-0 libmatroska7 libmpeg2-4 Aug 25 13:58:37 jessroom volumio[1244]: info: libmtp-common libmtp-runtime libmtp9 libopenmpt-modplug1 libprotobuf-lite32 Aug 25 13:58:37 jessroom volumio[1244]: info: libproxy-tools libproxy1v5 libresid-builder0c2a libsdl-image1.2 Aug 25 13:58:37 jessroom volumio[1244]: info: libsdl1.2debian libsecret-1-0 libsecret-common libsidplay2 libspatialaudio0 Aug 25 13:58:37 jessroom volumio[1244]: info: libtag1v5 libtag1v5-vanilla libusageenvironment3 libvlc-bin libvlc5 Aug 25 13:58:37 jessroom volumio[1244]: info: libvlccore9 libxcb-keysyms1 vlc-bin vlc-data vlc-plugin-base Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.956Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.956Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.957Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.958Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.959Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:37 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:37.960Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: 0 upgraded, 41 newly installed, 0 to remove and 93 not upgraded. Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.304Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: Need to get 8483 kB of archives. Aug 25 13:58:38 jessroom volumio[1244]: info: After this operation, 22.5 MB of additional disk space will be used. Aug 25 13:58:38 jessroom volumio[1244]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf libusageenvironment3 armhf 2025.05.24-1~bpo12+1 [7532 B] Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.315Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.315Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.316Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: Get:3 http://archive.raspberrypi.com/debian bookworm/main armhf libbasicusageenvironment2 armhf 2025.05.24-1~bpo12+1 [17.5 kB] Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.443Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf libgroupsock30 armhf 2025.05.24-1~bpo12+1 [24.1 kB] Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.501Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf liblivemedia116 armhf 2025.05.24-1~bpo12+1 [373 kB] Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.624Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:38 jessroom volumio[1244]: info: Get:2 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf liba52-0.7.4 armhf 0.7.4-20 [28.2 kB] Aug 25 13:58:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:38.769Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:7 http://archive.raspberrypi.com/debian bookworm/main armhf libvlccore9 armhf 1:3.0.23-0+deb12u1+rpt1 [571 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.043Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:6 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libaribb24-0 armhf 1.0.3-2 [25.6 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.058Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:8 http://archive.raspberrypi.com/debian bookworm/main armhf libvlc5 armhf 1:3.0.23-0+deb12u1+rpt1 [148 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.190Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:10 http://archive.raspberrypi.com/debian bookworm/main armhf libvlc-bin armhf 1:3.0.23-0+deb12u1+rpt1 [95.4 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.216Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:11 http://archive.raspberrypi.com/debian bookworm/main armhf vlc-bin armhf 1:3.0.23-0+deb12u1+rpt1 [142 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.253Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:12 http://archive.raspberrypi.com/debian bookworm/main armhf vlc-data all 1:3.0.23-0+deb12u1+rpt1 [474 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.275Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:13 http://archive.raspberrypi.com/debian bookworm/main armhf vlc-plugin-base armhf 1:3.0.23-0+deb12u1+rpt1 [4481 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.308Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:9 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libcddb2 armhf 1.3.2-7 [44.0 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.373Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:39 jessroom volumio[1244]: info: Get:14 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libdca0 armhf 0.0.7-2 [85.6 kB] Aug 25 13:58:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:39.757Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:40 jessroom volumio[1244]: info: Get:15 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libdvbpsi10 armhf 1.3.3-1 [45.3 kB] Aug 25 13:58:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:40.148Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:40 jessroom volumio[1244]: info: Get:16 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libdvdread8 armhf 6.1.3-1 [87.4 kB] Aug 25 13:58:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:40.552Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:40 jessroom volumio[1244]: info: Get:17 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libdvdnav4 armhf 6.1.1-1 [43.3 kB] Aug 25 13:58:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:40.940Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:41 jessroom volumio[1244]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libebml5 armhf 1.4.4-1+deb12u1 [41.6 kB] Aug 25 13:58:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:41.341Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:41 jessroom volumio[1244]: info: Get:19 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libidn12 armhf 1.41-1+b1 [77.0 kB] Aug 25 13:58:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:41.730Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:42 jessroom volumio[1244]: info: Get:20 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libkate1 armhf 0.4.1-11 [37.1 kB] Aug 25 13:58:42 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:42.131Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:42 jessroom volumio[1244]: info: Get:21 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf liblirc-client0 armhf 0.10.1-7.2 [44.9 kB] Aug 25 13:58:42 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:42.531Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:42 jessroom volumio[1244]: info: Get:22 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf liblua5.2-0 armhf 5.2.4-3 [85.7 kB] Aug 25 13:58:42 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:42.929Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:43 jessroom volumio[1244]: info: Get:23 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmatroska7 armhf 1.7.1-1 [89.7 kB] Aug 25 13:58:43 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:43.320Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="obtained new client token: AAFLNPZFZ02kh+OO+8jgWgbKoLS22+IFZ4dnOp4iM9/jLmdDEcbJCeset2bw08ECGPAzOJGxC1bv3SLECdMSfhz2kQFt7v8MmZvzHB+VOL+ugJy4F5Y1ayZe6xe58GSnILbyQfBvRZNV4XUZjS5gIh5mE10LlJJRj4C0FA2iYxcagLo3UwvRCAmj99lnkC61ttlc4tq1VlJYOEVSkLPB+88gi3UAV/ZlFsnQU2l1SxH7KLOPhiGvIg==" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=warning msg="failed to connect to AP ap-guc3.spotify.com:4070, retrying with a different AP" error="dial tcp 104.154.127.247:4070: connect: connection refused" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="connected to ap-guc3.spotify.com:443" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="completed keyexchange" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="completed challenge" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=info msg="authenticated AP" username="31************************dy" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=info msg="authenticated Login5" username="31************************dy" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=info msg="accepted zeroconf from JESS-S24+" username="31************************dy" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="dealer connection opened" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=trace msg="starting accesspoint recv loop" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=trace msg="starting dealer recv loop" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=trace msg="received accesspoint ping" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="received connection id: YThkMzNmZDItOTU1...QThDRkM1MkRGQQ==" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=trace msg="received accesspoint pong ack" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="put connect state because NEW_DEVICE" Aug 25 13:58:44 jessroom volumio[1244]: info: Get:24 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmpeg2-4 armhf 0.5.1-9 [44.9 kB] Aug 25 13:58:44 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:44.728Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="handling transfer player command from b2ffa422ef58fc990389ec25eecdbb33fbe225bc" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="resolved context of track" uri="spotify:playlist:4uLxwGuSyujNhObRhc3nZx" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=trace msg="fetched new page 0 with 626 items (list: 626)" uri="spotify:playlist:4uLxwGuSyujNhObRhc3nZx" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="shuffled context with seed 7581350582550998509 (len: 626, keep: 597)" uri="spotify:playlist:4uLxwGuSyujNhObRhc3nZx" Aug 25 13:58:44 jessroom go-librespot[3781]: time="2026-08-25T13:58:44-05:00" level=debug msg="loading track (paused: true, position: 114850ms)" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 436" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1409" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="emitting websocket event: will_play" Aug 25 13:58:45 jessroom volumio[1244]: info: Get:25 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmtp-common all 1.1.20-1 [58.6 kB] Aug 25 13:58:45 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:45.153Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="selected format OGG_VORBIS_320 (a3caea0c2b42300053c4bef1d4d3cbd6fe52ac5a)" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="requested aes key for file a3caea0c2b42300053c4bef1d4d3cbd6fe52ac5a, gid: 0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched first chunk of 19, total size is 9893667 bytes" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom volumio[1244]: info: Get:26 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmtp9 armhf 1.1.20-1 [195 kB] Aug 25 13:58:45 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:45.523Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 10/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 8/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 9/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="fetched chunk 7/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="seek to 114850ms (diff: 290ms, samples: 5064885, bytes: 4108542)" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="created new output device" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=info msg="loaded track \"Shine On\" (paused: true, position: 114850ms, duration: 279736ms, prefetched: false)" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="emitting websocket event: metadata" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="emitting websocket event: active" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="sending successful reply for dealer request" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Aug 25 13:58:45 jessroom volumio[1244]: info: Aligning Spotify Volume to Volumio Volume Aug 25 13:58:45 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:58:45 jessroom volumio[1244]: info: CorePlayQueue::getTrack 0 Aug 25 13:58:45 jessroom volumio[1244]: info: Setting Spotify Volume from Volumio: 86 Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:58:45 jessroom go-librespot[3781]: time="2026-08-25T13:58:45-05:00" level=trace msg="emitting websocket event: paused" Aug 25 13:58:45 jessroom volumio[1244]: info: Spotify is playing in volatile mode Aug 25 13:58:45 jessroom volumio[1244]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 13:58:45 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:58:45 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:45 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:45 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:45 jessroom volumio[1244]: info: Get:27 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmtp-runtime armhf 1.1.20-1 [47.5 kB] Aug 25 13:58:45 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:45.952Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:46 jessroom volumio[1244]: info: Get:28 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libopenmpt-modplug1 armhf 0.8.9.0-openmpt1-2 [20.2 kB] Aug 25 13:58:46 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:46.323Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:46 jessroom volumio[1244]: info: Get:29 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libprotobuf-lite32 armhf 3.21.12-3+deb12u1 [249 kB] Aug 25 13:58:46 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:46.569Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:47 jessroom volumio[1244]: info: Sending Spotify command with payload to local API: /player/volume Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="update volume requested to 56360/65535" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="handling resume player command from b2ffa422ef58fc990389ec25eecdbb33fbe225bc" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=trace msg="seek to 114850ms (diff: 290ms, samples: 5064885, bytes: 4108542)" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="resume track at 114560ms" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=trace msg="scheduling prefetch in 135s" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="sending successful reply for dealer request" Aug 25 13:58:47 jessroom volumio[1244]: info: Get:30 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libproxy1v5 armhf 0.4.18-1.2 [48.8 kB] Aug 25 13:58:47 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:47.686Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:58:47 jessroom go-librespot[3781]: time="2026-08-25T13:58:47-05:00" level=trace msg="emitting websocket event: playing" Aug 25 13:58:47 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:58:47 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:47 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:47 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:48 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:58:48 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:48 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:48 jessroom volumio[1244]: info: Get:31 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libproxy-tools armhf 0.4.18-1.2 [11.0 kB] Aug 25 13:58:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:48.127Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:48 jessroom volumio[1244]: info: Get:32 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libresid-builder0c2a armhf 2.1.1-15 [27.4 kB] Aug 25 13:58:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:48.473Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:48 jessroom volumio[1244]: info: Get:33 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl1.2debian armhf 1.2.15+dfsg2-8 [164 kB] Aug 25 13:58:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:48.866Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:48 jessroom go-librespot[3781]: time="2026-08-25T13:58:48-05:00" level=debug msg="update volume requested to 58982/65535" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="fetched chunk 11/18, size: 524288" uri="spotify:track:0A04reVJDzS42mALD52sQG" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="update volume requested to 61603/65535" Aug 25 13:58:49 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 90 Aug 25 13:58:49 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume90 Aug 25 13:58:49 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:49 jessroom volumio[1244]: info: Get:34 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsdl-image1.2 armhf 1.2.12-13+b2 [28.9 kB] Aug 25 13:58:49 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:49.312Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:49 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 94 Aug 25 13:58:49 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume94 Aug 25 13:58:49 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="update volume requested to 65535/65535" Aug 25 13:58:49 jessroom volumio[1244]: info: Get:35 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsecret-common all 0.20.5-3+rpi1 [28.5 kB] Aug 25 13:58:49 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:49.670Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:49 jessroom go-librespot[3781]: time="2026-08-25T13:58:49-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:49 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 100 Aug 25 13:58:49 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume100 Aug 25 13:58:49 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:49 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:50 jessroom volumio[1244]: info: Get:36 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libsecret-1-0 armhf 0.20.5-3+rpi1 [96.8 kB] Aug 25 13:58:50 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:50.061Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:50 jessroom volumio[1244]: info: Get:37 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libsidplay2 armhf 2.1.1-15 [89.2 kB] Aug 25 13:58:50 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:50.641Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:50 jessroom volumio[1244]: info: Get:38 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libspatialaudio0 armhf 0.3.0+git20180730+dfsg1-2+b1 [27.9 kB] Aug 25 13:58:50 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:50.935Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:51 jessroom volumio[1244]: info: Get:39 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libtag1v5-vanilla armhf 1.13-2+deb12u1 [243 kB] Aug 25 13:58:51 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:51.253Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:51 jessroom go-librespot[3781]: time="2026-08-25T13:58:51-05:00" level=debug msg="update volume requested to 62914/65535" Aug 25 13:58:51 jessroom volumio[1244]: info: Get:40 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libtag1v5 armhf 1.13-2+deb12u1 [18.8 kB] Aug 25 13:58:51 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:51.642Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:51 jessroom go-librespot[3781]: time="2026-08-25T13:58:51-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:51 jessroom go-librespot[3781]: time="2026-08-25T13:58:51-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:51 jessroom go-librespot[3781]: time="2026-08-25T13:58:51-05:00" level=debug msg="update volume requested to 60292/65535" Aug 25 13:58:51 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 96 Aug 25 13:58:51 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume96 Aug 25 13:58:51 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:51 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:51 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:52 jessroom volumio[1244]: info: Get:41 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf libxcb-keysyms1 armhf 0.4.0-1+b2 [15.9 kB] Aug 25 13:58:52 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:52.049Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="update volume requested to 52428/65535" Aug 25 13:58:52 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 92 Aug 25 13:58:52 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume92 Aug 25 13:58:52 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:52 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 80 Aug 25 13:58:52 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume80 Aug 25 13:58:52 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="update volume requested to 47185/65535" Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:52 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 72 Aug 25 13:58:52 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume72 Aug 25 13:58:52 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:52 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:52 jessroom go-librespot[3781]: time="2026-08-25T13:58:52-05:00" level=debug msg="update volume requested to 44564/65535" Aug 25 13:58:53 jessroom volumio[1244]: info: Fetched 8483 kB in 14s (603 kB/s) Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.056Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=debug msg="update volume requested to 41942/65535" Aug 25 13:58:53 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 68 Aug 25 13:58:53 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume68 Aug 25 13:58:53 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:53 jessroom volumio[1244]: info: Selecting previously unselected package liba52-0.7.4:armhf. Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.144Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:53 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 64 Aug 25 13:58:53 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume64 Aug 25 13:58:53 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:53 jessroom volumio[1244]: [620B blob data] Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.407Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Preparing to unpack .../00-liba52-0.7.4_0.7.4-20_armhf.deb ... Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=debug msg="update volume requested to 39321/65535" Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.412Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Unpacking liba52-0.7.4:armhf (0.7.4-20) ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.442Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Selecting previously unselected package libaribb24-0:armhf. Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.598Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Preparing to unpack .../01-libaribb24-0_1.0.3-2_armhf.deb ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.608Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Unpacking libaribb24-0:armhf (1.0.3-2) ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.623Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:53 jessroom go-librespot[3781]: time="2026-08-25T13:58:53-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:53 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 60 Aug 25 13:58:53 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume60 Aug 25 13:58:53 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:53 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:53 jessroom volumio[1244]: info: Selecting previously unselected package libusageenvironment3:armhf. Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.779Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Preparing to unpack .../02-libusageenvironment3_2025.05.24-1~bpo12+1_armhf.deb ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.793Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Unpacking libusageenvironment3:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.826Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Selecting previously unselected package libbasicusageenvironment2:armhf. Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.948Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Preparing to unpack .../03-libbasicusageenvironment2_2025.05.24-1~bpo12+1_armhf.deb ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.957Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:53 jessroom volumio[1244]: info: Unpacking libbasicusageenvironment2:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:58:53 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:53.970Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Selecting previously unselected package libcddb2. Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.100Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Preparing to unpack .../04-libcddb2_1.3.2-7_armhf.deb ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.113Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Unpacking libcddb2 (1.3.2-7) ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.129Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Selecting previously unselected package libdca0:armhf. Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.271Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Preparing to unpack .../05-libdca0_0.0.7-2_armhf.deb ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.285Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Unpacking libdca0:armhf (0.0.7-2) ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.303Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Selecting previously unselected package libdvbpsi10:armhf. Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.488Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Preparing to unpack .../06-libdvbpsi10_1.3.3-1_armhf.deb ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.496Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Unpacking libdvbpsi10:armhf (1.3.3-1) ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.504Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Selecting previously unselected package libdvdread8:armhf. Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.657Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Preparing to unpack .../07-libdvdread8_6.1.3-1_armhf.deb ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.667Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Unpacking libdvdread8:armhf (6.1.3-1) ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.699Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Selecting previously unselected package libdvdnav4:armhf. Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.847Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Preparing to unpack .../08-libdvdnav4_6.1.1-1_armhf.deb ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.858Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:54 jessroom volumio[1244]: info: Unpacking libdvdnav4:armhf (6.1.1-1) ... Aug 25 13:58:54 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:54.876Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package libebml5:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.035Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../09-libebml5_1.4.4-1+deb12u1_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.038Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking libebml5:armhf (1.4.4-1+deb12u1) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.057Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package libgroupsock30:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.208Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../10-libgroupsock30_2025.05.24-1~bpo12+1_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.217Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking libgroupsock30:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.228Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package libidn12:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.368Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../11-libidn12_1.41-1+b1_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.377Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking libidn12:armhf (1.41-1+b1) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.391Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package libkate1:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.577Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../12-libkate1_0.4.1-11_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.587Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking libkate1:armhf (0.4.1-11) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.601Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package liblirc-client0:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.742Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../13-liblirc-client0_0.10.1-7.2_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.749Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking liblirc-client0:armhf (0.10.1-7.2) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.757Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Selecting previously unselected package liblivemedia116:armhf. Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.928Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Preparing to unpack .../14-liblivemedia116_2025.05.24-1~bpo12+1_armhf.deb ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.935Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:55 jessroom volumio[1244]: info: Unpacking liblivemedia116:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:58:55 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:55.948Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Selecting previously unselected package liblua5.2-0:armhf. Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.148Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Preparing to unpack .../15-liblua5.2-0_5.2.4-3_armhf.deb ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.159Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Unpacking liblua5.2-0:armhf (5.2.4-3) ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.167Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Selecting previously unselected package libmatroska7:armhf. Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.351Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Preparing to unpack .../16-libmatroska7_1.7.1-1_armhf.deb ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.358Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Unpacking libmatroska7:armhf (1.7.1-1) ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.369Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Selecting previously unselected package libmpeg2-4:armhf. Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.600Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Preparing to unpack .../17-libmpeg2-4_0.5.1-9_armhf.deb ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.605Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Unpacking libmpeg2-4:armhf (0.5.1-9) ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.615Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Selecting previously unselected package libmtp-common. Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.806Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Preparing to unpack .../18-libmtp-common_1.1.20-1_all.deb ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.815Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:56 jessroom volumio[1244]: info: Unpacking libmtp-common (1.1.20-1) ... Aug 25 13:58:56 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:56.826Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Selecting previously unselected package libmtp9:armhf. Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.049Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Preparing to unpack .../19-libmtp9_1.1.20-1_armhf.deb ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.060Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Unpacking libmtp9:armhf (1.1.20-1) ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.088Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Selecting previously unselected package libmtp-runtime. Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.290Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Preparing to unpack .../20-libmtp-runtime_1.1.20-1_armhf.deb ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.293Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Unpacking libmtp-runtime (1.1.20-1) ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.304Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Selecting previously unselected package libopenmpt-modplug1:armhf. Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.458Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Preparing to unpack .../21-libopenmpt-modplug1_0.8.9.0-openmpt1-2_armhf.deb ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.462Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Unpacking libopenmpt-modplug1:armhf (0.8.9.0-openmpt1-2) ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.490Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Selecting previously unselected package libprotobuf-lite32:armhf. Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.643Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Preparing to unpack .../22-libprotobuf-lite32_3.21.12-3+deb12u1_armhf.deb ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.656Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Unpacking libprotobuf-lite32:armhf (3.21.12-3+deb12u1) ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.672Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Selecting previously unselected package libproxy1v5:armhf. Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.928Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Preparing to unpack .../23-libproxy1v5_0.4.18-1.2_armhf.deb ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.934Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:57 jessroom volumio[1244]: info: Unpacking libproxy1v5:armhf (0.4.18-1.2) ... Aug 25 13:58:57 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:57.948Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Selecting previously unselected package libproxy-tools. Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.091Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Preparing to unpack .../24-libproxy-tools_0.4.18-1.2_armhf.deb ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.099Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Unpacking libproxy-tools (0.4.18-1.2) ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.106Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Selecting previously unselected package libresid-builder0c2a. Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.358Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Preparing to unpack .../25-libresid-builder0c2a_2.1.1-15_armhf.deb ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.363Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Unpacking libresid-builder0c2a (2.1.1-15) ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.380Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Selecting previously unselected package libsdl1.2debian:armhf. Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.536Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Preparing to unpack .../26-libsdl1.2debian_1.2.15+dfsg2-8_armhf.deb ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.541Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Unpacking libsdl1.2debian:armhf (1.2.15+dfsg2-8) ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.556Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Selecting previously unselected package libsdl-image1.2:armhf. Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.778Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Preparing to unpack .../27-libsdl-image1.2_1.2.12-13+b2_armhf.deb ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.787Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Unpacking libsdl-image1.2:armhf (1.2.12-13+b2) ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.804Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Selecting previously unselected package libsecret-common. Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.947Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Preparing to unpack .../28-libsecret-common_0.20.5-3+rpi1_all.deb ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.954Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:58 jessroom volumio[1244]: info: Unpacking libsecret-common (0.20.5-3+rpi1) ... Aug 25 13:58:58 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:58.976Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Selecting previously unselected package libsecret-1-0:armhf. Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.129Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Preparing to unpack .../29-libsecret-1-0_0.20.5-3+rpi1_armhf.deb ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.137Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Unpacking libsecret-1-0:armhf (0.20.5-3+rpi1) ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.145Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Selecting previously unselected package libsidplay2. Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.328Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Preparing to unpack .../30-libsidplay2_2.1.1-15_armhf.deb ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.339Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Unpacking libsidplay2 (2.1.1-15) ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.350Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Selecting previously unselected package libspatialaudio0:armhf. Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.499Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Preparing to unpack .../31-libspatialaudio0_0.3.0+git20180730+dfsg1-2+b1_armhf.deb ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.510Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Unpacking libspatialaudio0:armhf (0.3.0+git20180730+dfsg1-2+b1) ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.526Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom go-librespot[3781]: time="2026-08-25T13:58:59-05:00" level=debug msg="update volume requested to 41942/65535" Aug 25 13:58:59 jessroom volumio[1244]: info: Selecting previously unselected package libtag1v5-vanilla:armhf. Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.677Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Preparing to unpack .../32-libtag1v5-vanilla_1.13-2+deb12u1_armhf.deb ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.692Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Unpacking libtag1v5-vanilla:armhf (1.13-2+deb12u1) ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.730Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom go-librespot[3781]: time="2026-08-25T13:58:59-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:58:59 jessroom go-librespot[3781]: time="2026-08-25T13:58:59-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:58:59 jessroom go-librespot[3781]: time="2026-08-25T13:58:59-05:00" level=debug msg="update volume requested to 44564/65535" Aug 25 13:58:59 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 64 Aug 25 13:58:59 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume64 Aug 25 13:58:59 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:58:59 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:58:59 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:58:59 jessroom volumio[1244]: info: Selecting previously unselected package libtag1v5:armhf. Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.945Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Preparing to unpack .../33-libtag1v5_1.13-2+deb12u1_armhf.deb ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.953Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:58:59 jessroom volumio[1244]: info: Unpacking libtag1v5:armhf (1.13-2+deb12u1) ... Aug 25 13:58:59 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:58:59.966Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="update volume requested to 47185/65535" Aug 25 13:59:00 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 68 Aug 25 13:59:00 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume68 Aug 25 13:59:00 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:00 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:00 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:00 jessroom volumio[1244]: info: Selecting previously unselected package libvlccore9:armhf. Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.118Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Preparing to unpack .../34-libvlccore9_1%3a3.0.23-0+deb12u1+rpt1_armhf.deb ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.126Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Unpacking libvlccore9:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.138Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:00 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 72 Aug 25 13:59:00 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume72 Aug 25 13:59:00 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:00 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:00 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:00 jessroom volumio[1244]: info: Selecting previously unselected package libvlc5:armhf. Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.400Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Preparing to unpack .../35-libvlc5_1%3a3.0.23-0+deb12u1+rpt1_armhf.deb ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.410Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Unpacking libvlc5:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.426Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Selecting previously unselected package libvlc-bin:armhf. Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.612Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Preparing to unpack .../36-libvlc-bin_1%3a3.0.23-0+deb12u1+rpt1_armhf.deb ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.627Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Unpacking libvlc-bin:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.670Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="handling skip_next player command from b2ffa422ef58fc990389ec25eecdbb33fbe225bc" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:00 jessroom volumio[1244]: info: Selecting previously unselected package vlc-bin. Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=trace msg="emitting websocket event: will_play" Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.888Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Preparing to unpack .../37-vlc-bin_1%3a3.0.23-0+deb12u1+rpt1_armhf.deb ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.899Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom volumio[1244]: info: Unpacking vlc-bin (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:00 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:00.915Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="selected format OGG_VORBIS_320 (ea544fc01cca16db5b43543d832e8d082d2b9578)" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:00 jessroom go-librespot[3781]: time="2026-08-25T13:59:00-05:00" level=debug msg="requested aes key for file ea544fc01cca16db5b43543d832e8d082d2b9578, gid: 3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="fetched first chunk of 20, total size is 10158073 bytes" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=info msg="loaded track \"the sound dogs make when they're sleeping\" (paused: false, position: 0ms, duration: 263035ms, prefetched: false)" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom volumio[1244]: info: Selecting previously unselected package vlc-data. Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.154Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:01 jessroom volumio[1244]: info: Preparing to unpack .../38-vlc-data_1%3a3.0.23-0+deb12u1+rpt1_all.deb ... Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.167Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:01 jessroom volumio[1244]: info: Unpacking vlc-data (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.187Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=trace msg="scheduling prefetch in 233s" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=trace msg="emitting websocket event: metadata" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="sending successful reply for dealer request" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:3k7B20Wr9fBpXUDPRv1o7o" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:01 jessroom go-librespot[3781]: time="2026-08-25T13:59:01-05:00" level=trace msg="emitting websocket event: playing" Aug 25 13:59:01 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:01 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:01 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:01 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:01 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:01 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:01 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:01 jessroom volumio[1244]: info: Selecting previously unselected package libxcb-keysyms1:armhf. Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.908Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:01 jessroom volumio[1244]: info: Preparing to unpack .../39-libxcb-keysyms1_0.4.0-1+b2_armhf.deb ... Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.917Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:01 jessroom volumio[1244]: info: Unpacking libxcb-keysyms1:armhf (0.4.0-1+b2) ... Aug 25 13:59:01 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:01.968Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:02 jessroom volumio[1244]: info: Selecting previously unselected package vlc-plugin-base:armhf. Aug 25 13:59:02 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:02.107Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:02 jessroom volumio[1244]: info: Preparing to unpack .../40-vlc-plugin-base_1%3a3.0.23-0+deb12u1+rpt1_armhf.deb ... Aug 25 13:59:02 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:02.116Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:02 jessroom volumio[1244]: info: Unpacking vlc-plugin-base:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:02 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:02.129Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=debug msg="update volume requested to 44564/65535" Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:02 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 68 Aug 25 13:59:02 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume68 Aug 25 13:59:02 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:02 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:02 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=debug msg="update volume requested to 41942/65535" Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:02 jessroom go-librespot[3781]: time="2026-08-25T13:59:02-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:02 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 64 Aug 25 13:59:02 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume64 Aug 25 13:59:02 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:02 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:02 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="update volume requested to 36700/65535" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="handling skip_next player command from b2ffa422ef58fc990389ec25eecdbb33fbe225bc" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=trace msg="emitting websocket event: will_play" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="selected format OGG_VORBIS_320 (27537a56444b3dc0e78787ec6a3091cac7b5fdfa)" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="requested aes key for file 27537a56444b3dc0e78787ec6a3091cac7b5fdfa, gid: 5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="fetched first chunk of 15, total size is 7352568 bytes" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=info msg="loaded track \"Downfall\" (paused: false, position: 1ms, duration: 177000ms, prefetched: false)" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=trace msg="scheduling prefetch in 147s" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=trace msg="emitting websocket event: metadata" Aug 25 13:59:03 jessroom go-librespot[3781]: time="2026-08-25T13:59:03-05:00" level=debug msg="sending successful reply for dealer request" Aug 25 13:59:04 jessroom go-librespot[3781]: time="2026-08-25T13:59:04-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:04 jessroom go-librespot[3781]: time="2026-08-25T13:59:04-05:00" level=trace msg="emitting websocket event: playing" Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:04 jessroom go-librespot[3781]: time="2026-08-25T13:59:04-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:04 jessroom go-librespot[3781]: time="2026-08-25T13:59:04-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:04 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 56 Aug 25 13:59:04 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume56 Aug 25 13:59:04 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:04 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:04 jessroom go-librespot[3781]: time="2026-08-25T13:59:04-05:00" level=debug msg="update volume requested to 34078/65535" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:05 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 52 Aug 25 13:59:05 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume52 Aug 25 13:59:05 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=debug msg="update volume requested to 31457/65535" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:05 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 48 Aug 25 13:59:05 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume48 Aug 25 13:59:05 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=debug msg="update volume requested to 28835/65535" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:05 jessroom go-librespot[3781]: time="2026-08-25T13:59:05-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:05 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 44 Aug 25 13:59:05 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume44 Aug 25 13:59:05 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:05 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libebml5:armhf (1.4.4-1+deb12u1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.235Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libproxy1v5:armhf (0.4.18-1.2) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.276Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libtag1v5-vanilla:armhf (1.13-2+deb12u1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.337Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libkate1:armhf (0.4.1-11) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.373Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libdvbpsi10:armhf (1.3.3-1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.411Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libxcb-keysyms1:armhf (0.4.0-1+b2) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.491Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libspatialaudio0:armhf (0.3.0+git20180730+dfsg1-2+b1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.526Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libmpeg2-4:armhf (0.5.1-9) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.552Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libaribb24-0:armhf (1.0.3-2) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.585Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libusageenvironment3:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.618Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libcddb2 (1.3.2-7) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.661Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libbasicusageenvironment2:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.692Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up liba52-0.7.4:armhf (0.7.4-20) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.731Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libdvdread8:armhf (6.1.3-1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.755Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libresid-builder0c2a (2.1.1-15) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.784Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up liblirc-client0:armhf (0.10.1-7.2) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.806Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libmtp-common (1.1.20-1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.870Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libsdl1.2debian:armhf (1.2.15+dfsg2-8) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.898Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libidn12:armhf (1.41-1+b1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.924Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libvlccore9:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.951Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:06 jessroom volumio[1244]: info: Setting up libsidplay2 (2.1.1-15) ... Aug 25 13:59:06 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:06.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libtag1v5:armhf (1.13-2+deb12u1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.007Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up liblua5.2-0:armhf (5.2.4-3) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.033Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libmatroska7:armhf (1.7.1-1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.060Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libdca0:armhf (0.0.7-2) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.086Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libprotobuf-lite32:armhf (3.21.12-3+deb12u1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.129Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libmtp9:armhf (1.1.20-1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.162Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libopenmpt-modplug1:armhf (0.8.9.0-openmpt1-2) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.184Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libmtp-runtime (1.1.20-1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.211Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up vlc-data (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.239Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libsecret-common (0.20.5-3+rpi1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.272Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libdvdnav4:armhf (6.1.1-1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.298Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libproxy-tools (0.4.18-1.2) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.326Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libsdl-image1.2:armhf (1.2.12-13+b2) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.352Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libgroupsock30:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.426Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libvlc5:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.458Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libsecret-1-0:armhf (0.20.5-3+rpi1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.490Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up libvlc-bin:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.521Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up liblivemedia116:armhf (2025.05.24-1~bpo12+1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.585Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up vlc-bin (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.606Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Setting up vlc-plugin-base:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.643Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.674Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:07 jessroom volumio[1244]: info: Processing triggers for udev (252.39-1~deb12u1+rpi1) ... Aug 25 13:59:07 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:07.797Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:10 jessroom volumio[1244]: info: Processing triggers for hicolor-icon-theme (0.17-2) ... Aug 25 13:59:10 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:10.009Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=debug msg="update volume requested to 31457/65535" Aug 25 13:59:10 jessroom volumio[1244]: info: Processing triggers for libvlc-bin:armhf (1:3.0.23-0+deb12u1+rpt1) ... Aug 25 13:59:10 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:10.299Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:10 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 48 Aug 25 13:59:10 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume48 Aug 25 13:59:10 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:10 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:10 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=debug msg="update volume requested to 34078/65535" Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:10 jessroom go-librespot[3781]: time="2026-08-25T13:59:10-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:10 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 52 Aug 25 13:59:10 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume52 Aug 25 13:59:10 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:10 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:10 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:11 jessroom go-librespot[3781]: time="2026-08-25T13:59:11-05:00" level=debug msg="update volume requested to 36700/65535" Aug 25 13:59:11 jessroom go-librespot[3781]: time="2026-08-25T13:59:11-05:00" level=debug msg="put connect state because VOLUME_CHANGED" Aug 25 13:59:11 jessroom go-librespot[3781]: time="2026-08-25T13:59:11-05:00" level=trace msg="emitting websocket event: volume" Aug 25 13:59:11 jessroom volumio[1244]: info: Setting Volumio Volume from Spotify: 56 Aug 25 13:59:11 jessroom volumio[1244]: info: VolumeController::SetAlsaVolume56 Aug 25 13:59:11 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:11 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:11 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:11 jessroom sudo[3978]: pam_unix(sudo:session): session closed for user root Aug 25 13:59:11 jessroom volumio[1244]: info: Installing mpv media player... Aug 25 13:59:11 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:11.958Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:11 jessroom sudo[4512]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get install -y mpv Aug 25 13:59:11 jessroom sudo[4512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 25 13:59:12 jessroom volumio[1244]: info: Reading package lists... Aug 25 13:59:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:12.078Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:12 jessroom volumio[1244]: info: Building dependency tree... Aug 25 13:59:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:12.589Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:12 jessroom volumio[1244]: info: Reading state information... Aug 25 13:59:12 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:12.598Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: The following additional packages will be installed: Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.566Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: libegl-mesa0 libegl1 libgbm1 libgl1-mesa-dri libglapi-mesa libglx-mesa0 Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.570Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: libmujs2 libsixel1 libuchardet0 libva-wayland2 libxpresent1 mesa-libgallium Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.578Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: python3-brotli python3-mutagen python3-pyxattr python3-websockets rtmpdump Aug 25 13:59:13 jessroom volumio[1244]: info: yt-dlp Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.584Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.584Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: Suggested packages: Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.599Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: python-mutagen-doc python-pyxattr-doc libfribidi-bin | bidiv phantomjs Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.605Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: The following NEW packages will be installed: Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.667Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: libegl-mesa0 libegl1 libmujs2 libsixel1 libuchardet0 libva-wayland2 Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.682Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: libxpresent1 mpv python3-brotli python3-mutagen python3-pyxattr Aug 25 13:59:13 jessroom volumio[1244]: info: python3-websockets rtmpdump yt-dlp Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.696Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.700Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: The following packages will be upgraded: Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.717Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:13 jessroom volumio[1244]: info: libgbm1 libgl1-mesa-dri libglapi-mesa libglx-mesa0 mesa-libgallium Aug 25 13:59:13 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:13.726Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio[1244]: info: 5 upgraded, 14 newly installed, 0 to remove and 88 not upgraded. Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.042Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio[1244]: info: Need to get 14.7 MB of archives. Aug 25 13:59:14 jessroom volumio[1244]: info: After this operation, 16.8 MB of additional disk space will be used. Aug 25 13:59:14 jessroom volumio[1244]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf libglx-mesa0 armhf 24.2.8-1~bpo12+rpt5 [179 kB] Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.049Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.052Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.054Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom go-librespot[3781]: time="2026-08-25T13:59:14-05:00" level=trace msg="sent dealer ping" Aug 25 13:59:14 jessroom go-librespot[3781]: time="2026-08-25T13:59:14-05:00" level=trace msg="received dealer pong" Aug 25 13:59:14 jessroom volumio[1244]: info: Get:2 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libegl1 armhf 1.6.0-1 [28.3 kB] Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.517Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio[1244]: info: Get:4 http://archive.raspberrypi.com/debian bookworm/main armhf libgbm1 armhf 24.2.8-1~bpo12+rpt5 [45.5 kB] Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio[1244]: info: Get:5 http://archive.raspberrypi.com/debian bookworm/main armhf mesa-libgallium armhf 24.2.8-1~bpo12+rpt5 [10.6 MB] Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.689Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:14 jessroom volumio[1244]: info: Get:3 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libmujs2 armhf 1.3.2-1 [90.7 kB] Aug 25 13:59:14 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:14.800Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio[1244]: info: Get:6 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libsixel1 armhf 1.10.3-3 [81.1 kB] Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.155Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio[1244]: info: Get:7 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libuchardet0 armhf 0.0.7-1 [65.0 kB] Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.578Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio[1244]: info: Get:8 http://archive.raspberrypi.com/debian bookworm/main armhf libglapi-mesa armhf 24.2.8-1~bpo12+rpt5 [68.5 kB] Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.586Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio[1244]: info: Get:9 http://archive.raspberrypi.com/debian bookworm/main armhf libgl1-mesa-dri armhf 24.2.8-1~bpo12+rpt5 [51.3 kB] Aug 25 13:59:15 jessroom volumio[1244]: info: Get:10 http://archive.raspberrypi.com/debian bookworm/main armhf libegl-mesa0 armhf 24.2.8-1~bpo12+rpt5 [141 kB] Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.592Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.594Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:15 jessroom volumio[1244]: info: Get:11 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libva-wayland2 armhf 2.17.0-1 [17.1 kB] Aug 25 13:59:15 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:15.954Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:16 jessroom go-librespot[3781]: time="2026-08-25T13:59:16-05:00" level=debug msg="fetched chunk 4/14, size: 524288" uri="spotify:track:5FWHX5E1egbqVLoNupc9vf" Aug 25 13:59:17 jessroom volumio[1244]: info: Get:12 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf libxpresent1 armhf 1.0.0-2 [7316 B] Aug 25 13:59:17 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:17.888Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:18 jessroom volumio[1244]: info: Get:13 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf mpv armhf 0.35.1-4 [1042 kB] Aug 25 13:59:18 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:18.038Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:18 jessroom volumio[1244]: info: Get:14 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-brotli armhf 1.0.9-2+b3 [278 kB] Aug 25 13:59:18 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:18.883Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:18 jessroom volumio[1244]: info: Get:15 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf python3-mutagen all 1.46.0-1 [135 kB] Aug 25 13:59:18 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:18.907Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:18 jessroom volumio[1244]: info: Get:16 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf python3-pyxattr armhf 0.8.1-1 [15.8 kB] Aug 25 13:59:18 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:18.918Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:19 jessroom volumio[1244]: info: Get:17 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf python3-websockets all 10.4-1 [62.8 kB] Aug 25 13:59:19 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:19.035Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:19 jessroom volumio[1244]: info: Get:18 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf rtmpdump armhf 2.4+20151223.gitfa8646d.1-2+b2 [47.7 kB] Aug 25 13:59:19 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:19.053Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:19 jessroom volumio[1244]: info: Get:19 http://raspbian.mirror.axinja.net/raspbian bookworm/main armhf yt-dlp all 2023.03.04-1 [1778 kB] Aug 25 13:59:19 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:19.197Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:20 jessroom volumio[1244]: info: Fetched 14.7 MB in 6s (2612 kB/s) Aug 25 13:59:20 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:20.486Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:20 jessroom volumio[1244]: [620B blob data] Aug 25 13:59:20 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:20.664Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:20 jessroom volumio[1244]: info: Preparing to unpack .../00-libglx-mesa0_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:20 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:20.675Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:20 jessroom volumio[1244]: info: Unpacking libglx-mesa0:armhf (24.2.8-1~bpo12+rpt5) over (24.2.8-1~bpo12+rpt4) ... Aug 25 13:59:20 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:20.715Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:20 jessroom volumio[1244]: info: Preparing to unpack .../01-libgbm1_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:21 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:21.002Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:21 jessroom volumio[1244]: info: Unpacking libgbm1:armhf (24.2.8-1~bpo12+rpt5) over (24.2.8-1~bpo12+rpt4) ... Aug 25 13:59:21 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:21.031Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:21 jessroom volumio[1244]: info: Preparing to unpack .../02-mesa-libgallium_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:21 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:21.237Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:21 jessroom volumio[1244]: info: Unpacking mesa-libgallium:armhf (24.2.8-1~bpo12+rpt5) over (24.2.8-1~bpo12+rpt4) ... Aug 25 13:59:21 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:21.277Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:21 jessroom go-librespot[3781]: time="2026-08-25T13:59:21-05:00" level=debug msg="handling skip_next player command from b2ffa422ef58fc990389ec25eecdbb33fbe225bc" Aug 25 13:59:21 jessroom go-librespot[3781]: time="2026-08-25T13:59:21-05:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=trace msg="emitting websocket event: will_play" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="selected format OGG_VORBIS_320 (a0e902c986a073e74525e4509a5748180ca4cec1)" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="requested aes key for file a0e902c986a073e74525e4509a5748180ca4cec1, gid: 0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="fetched first chunk of 9, total size is 4463940 bytes" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=info msg="loaded track \"6 Hour ASMR Rain Sound\" (paused: false, position: 0ms, duration: 93090ms, prefetched: false)" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="fetched chunk 2/8, size: 524288" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="fetched chunk 3/8, size: 524288" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=trace msg="scheduling prefetch in 63s" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=trace msg="emitting websocket event: metadata" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="sending successful reply for dealer request" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="fetched chunk 1/8, size: 524288" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Aug 25 13:59:22 jessroom go-librespot[3781]: time="2026-08-25T13:59:22-05:00" level=trace msg="emitting websocket event: playing" Aug 25 13:59:22 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:22 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:22 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 13:59:22 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:23 jessroom volumio[1244]: info: CoreCommandRouter::servicePushState Aug 25 13:59:23 jessroom volumio[1244]: info: CoreStateMachine::pushState Aug 25 13:59:23 jessroom volumio[1244]: info: CoreCommandRouter::volumioPushState Aug 25 13:59:23 jessroom volumio[1244]: info: Preparing to unpack .../03-libglapi-mesa_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:23 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:23.682Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:23 jessroom volumio[1244]: info: Unpacking libglapi-mesa:armhf (24.2.8-1~bpo12+rpt5) over (24.2.8-1~bpo12+rpt4) ... Aug 25 13:59:23 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:23.711Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:23 jessroom volumio[1244]: info: Preparing to unpack .../04-libgl1-mesa-dri_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:23 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:23.933Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Unpacking libgl1-mesa-dri:armhf (24.2.8-1~bpo12+rpt5) over (24.2.8-1~bpo12+rpt4) ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.008Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Selecting previously unselected package libegl-mesa0:armhf. Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.307Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Preparing to unpack .../05-libegl-mesa0_24.2.8-1~bpo12+rpt5_armhf.deb ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.317Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Unpacking libegl-mesa0:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.334Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Selecting previously unselected package libegl1:armhf. Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.519Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Preparing to unpack .../06-libegl1_1.6.0-1_armhf.deb ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.526Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Unpacking libegl1:armhf (1.6.0-1) ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.542Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Selecting previously unselected package libmujs2:armhf. Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.729Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Preparing to unpack .../07-libmujs2_1.3.2-1_armhf.deb ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.738Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Unpacking libmujs2:armhf (1.3.2-1) ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.746Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Selecting previously unselected package libsixel1:armhf. Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.927Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Preparing to unpack .../08-libsixel1_1.10.3-3_armhf.deb ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.935Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:24 jessroom volumio[1244]: info: Unpacking libsixel1:armhf (1.10.3-3) ... Aug 25 13:59:24 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:24.950Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Selecting previously unselected package libuchardet0:armhf. Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.108Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Preparing to unpack .../09-libuchardet0_0.0.7-1_armhf.deb ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.114Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Unpacking libuchardet0:armhf (0.0.7-1) ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.120Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Selecting previously unselected package libva-wayland2:armhf. Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.278Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Preparing to unpack .../10-libva-wayland2_2.17.0-1_armhf.deb ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.281Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Unpacking libva-wayland2:armhf (2.17.0-1) ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.292Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Selecting previously unselected package libxpresent1:armhf. Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.476Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Preparing to unpack .../11-libxpresent1_1.0.0-2_armhf.deb ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.488Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Unpacking libxpresent1:armhf (1.0.0-2) ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.505Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Selecting previously unselected package mpv. Aug 25 13:59:25 jessroom volumio[1244]: info: Preparing to unpack .../12-mpv_0.35.1-4_armhf.deb ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.660Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.663Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:25 jessroom volumio[1244]: info: Unpacking mpv (0.35.1-4) ... Aug 25 13:59:25 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:25.707Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio[1244]: info: Selecting previously unselected package python3-brotli. Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.282Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio[1244]: info: Preparing to unpack .../13-python3-brotli_1.0.9-2+b3_armhf.deb ... Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.296Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio[1244]: info: Unpacking python3-brotli (1.0.9-2+b3) ... Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.310Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio[1244]: info: Selecting previously unselected package python3-mutagen. Aug 25 13:59:26 jessroom volumio[1244]: info: Preparing to unpack .../14-python3-mutagen_1.46.0-1_all.deb ... Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.649Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.650Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:26 jessroom volumio[1244]: info: Unpacking python3-mutagen (1.46.0-1) ... Aug 25 13:59:26 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:26.667Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Selecting previously unselected package python3-pyxattr:armhf. Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.319Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Preparing to unpack .../15-python3-pyxattr_0.8.1-1_armhf.deb ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.327Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Unpacking python3-pyxattr:armhf (0.8.1-1) ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.355Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Selecting previously unselected package python3-websockets. Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.497Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Preparing to unpack .../16-python3-websockets_10.4-1_all.deb ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.510Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Unpacking python3-websockets (10.4-1) ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.524Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Selecting previously unselected package rtmpdump. Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.936Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Preparing to unpack .../17-rtmpdump_2.4+20151223.gitfa8646d.1-2+b2_armhf.deb ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.943Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:27 jessroom volumio[1244]: info: Unpacking rtmpdump (2.4+20151223.gitfa8646d.1-2+b2) ... Aug 25 13:59:27 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:27.956Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:29 jessroom volumio[1244]: info: Selecting previously unselected package yt-dlp. Aug 25 13:59:29 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:29.298Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:29 jessroom volumio[1244]: info: Preparing to unpack .../18-yt-dlp_2023.03.04-1_all.deb ... Aug 25 13:59:29 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:29.309Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:29 jessroom volumio[1244]: info: Unpacking yt-dlp (2023.03.04-1) ... Aug 25 13:59:29 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:29.335Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 25 13:59:31 jessroom volumio[1244]: verbose: New Socket.io Connection to 192.168.1.25 from 192.168.1.250 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: Listing playlists Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: Listing playlists Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: Listing playlists Aug 25 13:59:31 jessroom volumio[1244]: info: Received Get System Info Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 13:59:31 jessroom volumio[1244]: info: Discovery: Getting this device information Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::volumioGetState Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 13:59:31 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 13:59:33 jessroom go-librespot[3781]: time="2026-08-25T13:59:33-05:00" level=debug msg="fetched chunk 4/8, size: 524288" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:38 jessroom volumio[1244]: info: Setting up libmujs2:armhf (1.3.2-1) ... Aug 25 13:59:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:38.456Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:38 jessroom volumio[1244]: info: Setting up python3-mutagen (1.46.0-1) ... Aug 25 13:59:38 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:38.489Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:39 jessroom volumio[1244]: info: Setting up libxpresent1:armhf (1.0.0-2) ... Aug 25 13:59:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:39.785Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:39 jessroom volumio[1244]: info: Setting up python3-brotli (1.0.9-2+b3) ... Aug 25 13:59:39 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:39.813Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up libsixel1:armhf (1.10.3-3) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.246Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up libglapi-mesa:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.277Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up libva-wayland2:armhf (2.17.0-1) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.315Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up rtmpdump (2.4+20151223.gitfa8646d.1-2+b2) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.352Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up libuchardet0:armhf (0.0.7-1) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.391Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up python3-websockets (10.4-1) ... Aug 25 13:59:40 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:40.423Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:40 jessroom volumio[1244]: info: Setting up python3-pyxattr:armhf (0.8.1-1) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.001Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:41 jessroom volumio[1244]: info: Setting up mesa-libgallium:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.088Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:41 jessroom volumio[1244]: info: Setting up libgbm1:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.120Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:41 jessroom volumio[1244]: info: Setting up libgl1-mesa-dri:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.156Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:41 jessroom volumio[1244]: info: Setting up libegl-mesa0:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.212Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:41 jessroom volumio[1244]: info: Setting up yt-dlp (2023.03.04-1) ... Aug 25 13:59:41 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:41.250Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:44 jessroom go-librespot[3781]: time="2026-08-25T13:59:44-05:00" level=debug msg="fetched chunk 5/8, size: 524288" uri="spotify:track:0ER1thrA8oRUJYdXepi4YM" Aug 25 13:59:44 jessroom go-librespot[3781]: time="2026-08-25T13:59:44-05:00" level=trace msg="sent dealer ping" Aug 25 13:59:44 jessroom go-librespot[3781]: time="2026-08-25T13:59:44-05:00" level=trace msg="received dealer pong" Aug 25 13:59:46 jessroom volumio[1244]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/bookworm/armhf Aug 25 13:59:46 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:46.631Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:46 jessroom volumio[1244]: info: Setting up libegl1:armhf (1.6.0-1) ... Aug 25 13:59:46 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:46.934Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:46 jessroom volumio[1244]: info: Setting up libglx-mesa0:armhf (24.2.8-1~bpo12+rpt5) ... Aug 25 13:59:46 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:46.967Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:46 jessroom volumio[1244]: info: Setting up mpv (0.35.1-4) ... Aug 25 13:59:47 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:47.000Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:47 jessroom volumio[1244]: info: Processing triggers for libc-bin (2.36-9+rpt2+deb12u13) ... Aug 25 13:59:47 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:47.043Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:47 jessroom volumio[1244]: info: Processing triggers for hicolor-icon-theme (0.17-2) ... Aug 25 13:59:47 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:47.138Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:47 jessroom volumio[1244]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/soundcloud/volumio/bookworm/armhf Aug 25 13:59:47 jessroom volumio[1244]: info: Folder /tmp/plugins removed Aug 25 13:59:47 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:47.963Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:48 jessroom sudo[4512]: pam_unix(sudo:session): session closed for user root Aug 25 13:59:48 jessroom sudo[3823]: pam_unix(sudo:session): session closed for user root Aug 25 13:59:48 jessroom volumio[1244]: info: SoundCloud plugin installed Aug 25 13:59:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:48.084Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:48 jessroom volumio[1244]: Plugin install end detected on script Aug 25 13:59:48 jessroom volumio[1244]: info: Install script completed Aug 25 13:59:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:48.094Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:48 jessroom volumio[1244]: info: Adding reference to registry Aug 25 13:59:48 jessroom volumio5-onboarding[1875]: time=2026-08-25T18:59:48.104Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Aug 25 13:59:48 jessroom volumio[1244]: info: Done installing plugin. Aug 25 13:59:48 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 13:59:48 jessroom volumio[1244]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 25 13:59:48 jessroom volumio[1244]: info: Folder /tmp/plugins removed Aug 25 13:59:48 jessroom volumio[1244]: info: Folder /tmp/downloaded_plugin.zip removed Aug 25 13:59:48 jessroom volumio[1244]: info: Folder /data/temp removed Aug 25 13:59:48 jessroom volumio[1244]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:59:48 jessroom volumio[1244]: Error: Unable to resolve or reject the same promise twice Aug 25 13:59:48 jessroom volumio[1244]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 25 13:59:48 jessroom volumio[1244]: at /volumio/app/pluginmanager.js:1087:11 Aug 25 13:59:48 jessroom volumio[1244]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Aug 25 13:59:48 jessroom volumio[1244]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Aug 25 13:59:48 jessroom volumio[1244]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Aug 25 13:59:48 jessroom volumio[1244]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Aug 25 13:59:48 jessroom volumio[1244]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Aug 25 13:59:48 jessroom volumio[1244]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Aug 25 13:59:48 jessroom volumio[1244]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Aug 25 13:59:48 jessroom volumio[1244]: at FSReqCallback.oncomplete (node:fs:190:23) Aug 25 13:59:48 jessroom volumio[1244]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 13:59:48 jessroom sudo[4861]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 13:58' Aug 25 13:59:48 jessroom sudo[4861]: 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"