-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2026-04-14 23:15:51 CEST. -- Apr 14 23:14:41 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 23:15:21 volumio volumio[1064]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:21 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:21.032+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio[1064]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:24 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:24 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:24.155+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:24.638+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio[1064]: info: Check plugin dependencies Apr 14 23:15:24 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 23:15:24 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:24.686+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio[1064]: info: Checking if plugin already exists Apr 14 23:15:24 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:24.692+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio[1064]: info: Rename folder Apr 14 23:15:24 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:24 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:24.722+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:24 volumio volumio[1064]: info: Move to category Apr 14 23:15:26 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:26.144+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:26 volumio volumio[1064]: info: Checking if install.sh is present Apr 14 23:15:26 volumio volumio[1064]: info: Executing install.sh Apr 14 23:15:26 volumio sudo[2987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Apr 14 23:15:26 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 14 23:15:26 volumio volumio[1064]: info: Installing Go-librespot Apr 14 23:15:26 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:26.221+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:26 volumio volumio[1064]: info: Checking old vollibrespot installs Apr 14 23:15:26 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:26.229+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:26 volumio systemd[1]: Reloading. Apr 14 23:15:26 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 14 23:15:26 volumio volumio[1064]: info: Downloading daemon Apr 14 23:15:26 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:26.738+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio volumio[1064]: info: Creating directories Apr 14 23:15:30 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:30.202+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio volumio[1064]: info: Creating data directory Apr 14 23:15:30 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:30.208+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio volumio[1064]: info: Creating Start Script Apr 14 23:15:30 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:30.221+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio systemd[1]: Reloading. Apr 14 23:15:30 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 14 23:15:30 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root Apr 14 23:15:30 volumio volumio[1064]: Plugin install end detected on script Apr 14 23:15:30 volumio volumio[1064]: info: Install script completed Apr 14 23:15:30 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:30.687+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio volumio[1064]: info: Adding reference to registry Apr 14 23:15:30 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:30.694+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:30 volumio volumio[1064]: info: Done installing plugin. Apr 14 23:15:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 23:15:30 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 23:15:30 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:30 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:30 volumio volumio[1064]: info: Folder /data/temp removed Apr 14 23:15:35 volumio volumio[1064]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:35 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:35.100+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:37 volumio volumio[1064]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:37 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:37 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:37.391+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:37 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:37.874+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:37 volumio volumio[1064]: info: Check plugin dependencies Apr 14 23:15:37 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 23:15:37 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:37.922+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:37 volumio volumio[1064]: info: Checking if plugin already exists Apr 14 23:15:37 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:37.928+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:37 volumio volumio[1064]: info: An error occurred installing the plugin. Rolling back config Apr 14 23:15:37 volumio volumio[1064]: info: Plugin folders cleanup Apr 14 23:15:37 volumio volumio[1064]: info: Scanning into folder /volumio/app/plugins/ Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category audio_interface Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category miscellanea Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category music_service Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category plugins.json Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category system_controller Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category user_interface Apr 14 23:15:37 volumio volumio[1064]: info: Scanning into folder /data/plugins/ Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category music_service Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category peppymeterbasic Apr 14 23:15:37 volumio volumio[1064]: info: Scanning category user_interface Apr 14 23:15:37 volumio volumio[1064]: info: Plugin folders cleanup completed Apr 14 23:15:37 volumio volumio[1064]: info: Error: Error Apr 14 23:15:37 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:37 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:37 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:38 volumio volumio[1064]: info: Folder /data/temp removed Apr 14 23:15:44 volumio volumio[1064]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:44 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:44.634+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:46 volumio volumio[1064]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:46 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:46.597+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:46 volumio volumio[1064]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:46 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:46.788+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:49 volumio volumio[1064]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:49 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:49 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:49.994+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:50 volumio volumio[1064]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Apr 14 23:15:50 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:50 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:50.214+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:50 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:50.499+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:50 volumio volumio[1064]: info: Check plugin dependencies Apr 14 23:15:50 volumio volumio[1064]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 23:15:50 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:50.556+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:50 volumio volumio[1064]: info: Checking if plugin already exists Apr 14 23:15:50 volumio volumio5-onboarding[1943]: time=2026-04-14T23:15:50.562+02:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Apr 14 23:15:50 volumio volumio[1064]: info: An error occurred installing the plugin. Rolling back config Apr 14 23:15:50 volumio volumio[1064]: info: Plugin folders cleanup Apr 14 23:15:50 volumio volumio[1064]: info: Scanning into folder /volumio/app/plugins/ Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category audio_interface Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category miscellanea Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category music_service Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category plugins.json Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category system_controller Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category user_interface Apr 14 23:15:50 volumio volumio[1064]: info: Scanning into folder /data/plugins/ Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category music_service Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category peppymeterbasic Apr 14 23:15:50 volumio volumio[1064]: info: Scanning category user_interface Apr 14 23:15:50 volumio volumio[1064]: info: Plugin folders cleanup completed Apr 14 23:15:50 volumio volumio[1064]: info: Error: Error Apr 14 23:15:50 volumio volumio[1064]: info: Folder /tmp/plugins removed Apr 14 23:15:50 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:50 volumio volumio[1064]: info: Folder /tmp/downloaded_plugin.zip removed Apr 14 23:15:50 volumio volumio[1064]: info: Folder /data/temp removed Apr 14 23:15:50 volumio volumio[1064]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 23:15:50 volumio volumio[1064]: Error: Unable to resolve or reject the same promise twice Apr 14 23:15:50 volumio volumio[1064]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 14 23:15:50 volumio volumio[1064]: at /volumio/app/pluginmanager.js:1087:11 Apr 14 23:15:50 volumio volumio[1064]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Apr 14 23:15:50 volumio volumio[1064]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Apr 14 23:15:50 volumio volumio[1064]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Apr 14 23:15:50 volumio volumio[1064]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Apr 14 23:15:50 volumio volumio[1064]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Apr 14 23:15:50 volumio volumio[1064]: at /volumio/node_modules/fs-extra/lib/remove/rimraf.js:213:24 Apr 14 23:15:50 volumio volumio[1064]: at CB (/volumio/node_modules/fs-extra/lib/remove/rimraf.js:57:5) Apr 14 23:15:50 volumio volumio[1064]: at FSReqCallback.oncomplete (fs.js:171:23) Apr 14 23:15:50 volumio volumio[1064]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 23:15:51 volumio sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-14 23:14 Apr 14 23:15:51 volumio sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"