-- 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"