Jul 08 19:01:22 volumio volumio[8954]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 08 19:01:22 volumio volumio[8954]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/spop.zip Jul 08 19:01:22 volumio volumio[8954]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/spop.zip Jul 08 19:01:22 volumio volumio[8954]: info: Folder /tmp/plugins removed Jul 08 19:01:22 volumio volumio[8954]: info: Check plugin dependencies Jul 08 19:01:22 volumio volumio[8954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 19:01:22 volumio volumio[8954]: info: Checking if plugin already exists Jul 08 19:01:22 volumio volumio[8954]: info: Rename folder Jul 08 19:01:22 volumio volumio[8954]: info: Folder /tmp/downloaded_plugin.zip removed Jul 08 19:01:22 volumio volumio[8954]: info: Move to category Jul 08 19:01:22 volumio volumio[8954]: error: Could not retrieve plugin system_controller my_volumio Jul 08 19:01:34 volumio volumio[8954]: info: Checking if install.sh is present Jul 08 19:01:34 volumio volumio[8954]: info: Executing install.sh Jul 08 19:01:34 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 08 19:01:34 volumio dbus-daemon[782]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.142' (uid=0 pid=16777 comm="timedatectl show --property=NTPSynchronized --valu") Jul 08 19:01:34 volumio sudo[16776]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/spop/install.sh Jul 08 19:01:34 volumio sudo[16776]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 08 19:01:34 volumio volumio[8954]: info: Installing Go-librespot Jul 08 19:01:35 volumio volumio[8954]: info: Checking old vollibrespot installs Jul 08 19:01:35 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 08 19:01:35 volumio systemd[1]: Reloading. Jul 08 19:01:35 volumio dbus-daemon[782]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 08 19:01:35 volumio setdatetime-helper.sh[16773]: Time is already synchronized. Jul 08 19:01:35 volumio systemd-gpt-auto-generator[16794]: EFI loader partition unknown, exiting. Jul 08 19:01:35 volumio systemd-gpt-auto-generator[16794]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jul 08 19:01:35 volumio systemd[1]: Configuration file /lib/systemd/system/vsound.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 08 19:01:35 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 08 19:01:35 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 08 19:01:35 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 08 19:01:35 volumio volumio[8954]: info: Downloading daemon Jul 08 19:01:38 volumio volumio[8954]: info: Creating directories Jul 08 19:01:38 volumio volumio[8954]: info: Creating data directory Jul 08 19:01:38 volumio volumio[8954]: info: Creating Start Script Jul 08 19:01:38 volumio systemd[1]: Reloading. Jul 08 19:01:38 volumio systemd-gpt-auto-generator[16824]: EFI loader partition unknown, exiting. Jul 08 19:01:38 volumio systemd-gpt-auto-generator[16824]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) Jul 08 19:01:39 volumio systemd[1]: Configuration file /lib/systemd/system/vsound.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jul 08 19:01:39 volumio sudo[16776]: pam_unix(sudo:session): session closed for user root Jul 08 19:01:39 volumio volumio[8954]: Plugin install end detected on script Jul 08 19:01:39 volumio volumio[8954]: info: Install script completed Jul 08 19:01:39 volumio volumio[8954]: info: Adding reference to registry Jul 08 19:01:39 volumio volumio[8954]: info: Done installing plugin. Jul 08 19:01:39 volumio volumio[8954]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 08 19:01:39 volumio volumio[8954]: error: Could not retrieve plugin system_controller my_volumio Jul 08 19:01:39 volumio volumio[8954]: info: Folder /tmp/plugins removed Jul 08 19:01:39 volumio volumio[8954]: info: Folder /tmp/downloaded_plugin.zip removed Jul 08 19:01:39 volumio volumio[8954]: info: Folder /data/temp removed Jul 08 19:02:05 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 08 19:02:40 volumio volumio[8954]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 08 19:02:41 volumio volumio[8954]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 08 19:02:41 volumio volumio[8954]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/now_playing.zip Jul 08 19:02:41 volumio volumio[8954]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/now_playing.zip Jul 08 19:02:41 volumio volumio[8954]: info: Folder /tmp/plugins removed Jul 08 19:02:42 volumio volumio[8954]: info: Check plugin dependencies Jul 08 19:02:42 volumio volumio[8954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 08 19:02:42 volumio volumio[8954]: info: Checking if plugin already exists Jul 08 19:02:42 volumio volumio[8954]: info: Rename folder Jul 08 19:02:42 volumio volumio[8954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 19:02:42 volumio volumio[8954]: TypeError: Cannot read properties of undefined (reading 'plugin_type') Jul 08 19:02:42 volumio volumio[8954]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1644:56) Jul 08 19:02:42 volumio volumio[8954]: at PluginManager.getAvailablePlugins (/volumio/app/pluginmanager.js:1691:24) Jul 08 19:02:42 volumio volumio[8954]: at CoreCommandRouter.getAvailablePlugins (/volumio/app/index.js:1544:29) Jul 08 19:02:42 volumio volumio[8954]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1354:45) Jul 08 19:02:42 volumio volumio[8954]: at Socket.emit (node:events:514:28) Jul 08 19:02:42 volumio volumio[8954]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 08 19:02:42 volumio volumio[8954]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jul 08 19:02:42 volumio volumio[8954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 19:02:43 volumio sudo[17177]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-08 19:01' Jul 08 19:02:43 volumio sudo[17177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="9807089f93a51a36bddd01d7490608fa210b1ca5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Jun 19:35:51 CEST 2025" VOLUMIO_VERSION="0.001" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f32bdcb50f8f6aeb31a0dce71e64e55a"