Oct 15 19:46:04 volumio volumio[1245]: info: Enabling plugin Systeminfo Oct 15 19:46:04 volumio volumio[1245]: info: Loading plugin "Systeminfo"... Oct 15 19:46:04 volumio volumio[1245]: info: PLUGIN START: Systeminfo Oct 15 19:46:04 volumio volumio[1245]: info: Done. Oct 15 19:46:16 volumio volumio[1245]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf Oct 15 19:46:16 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:16.373Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:18 volumio volumio[1245]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf Oct 15 19:46:18 volumio volumio[1245]: info: Folder /tmp/plugins removed Oct 15 19:46:18 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:18.912Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:19 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 19:46:19 volumio volumio[1245]: info: Systeminfo: --[object Object] Oct 15 19:46:19 volumio volumio[1245]: info: Systeminfo web UI listening on port 12334 Oct 15 19:46:19 volumio volumio[1245]: info: Check plugin dependencies Oct 15 19:46:19 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:19.950Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 19:46:20 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:20.022Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio volumio[1245]: info: Checking if plugin already exists Oct 15 19:46:20 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:20.030Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio volumio[1245]: info: Rename folder Oct 15 19:46:20 volumio volumio[1245]: info: Folder /tmp/downloaded_plugin.zip removed Oct 15 19:46:20 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:20.060Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio volumio[1245]: info: Move to category Oct 15 19:46:20 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:20.112Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio volumio[1245]: info: Checking if install.sh is present Oct 15 19:46:20 volumio volumio[1245]: info: Executing install.sh Oct 15 19:46:20 volumio sudo[4537]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Oct 15 19:46:20 volumio sudo[4537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 19:46:20 volumio volumio[1245]: info: Installing geo-tz node dependency Oct 15 19:46:20 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:20.162Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:20 volumio su[4540]: (to volumio) root on none Oct 15 19:46:20 volumio su[4540]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Oct 15 19:46:20 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Oct 15 19:46:20 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 15 19:46:20 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 15 19:46:20 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Oct 15 19:46:20 volumio (systemd)[4542]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Oct 15 19:46:21 volumio systemd[4542]: Queued start job for default target default.target. Oct 15 19:46:21 volumio systemd[4542]: Created slice app.slice - User Application Slice. Oct 15 19:46:21 volumio systemd[4542]: Reached target paths.target - Paths. Oct 15 19:46:21 volumio systemd[4542]: Reached target timers.target - Timers. Oct 15 19:46:21 volumio systemd[4542]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Oct 15 19:46:21 volumio systemd[4542]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 15 19:46:21 volumio systemd[4542]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 15 19:46:21 volumio systemd[4542]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 15 19:46:21 volumio systemd[4542]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 15 19:46:21 volumio systemd[4542]: Reached target sockets.target - Sockets. Oct 15 19:46:21 volumio systemd[4542]: Reached target basic.target - Basic System. Oct 15 19:46:21 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Oct 15 19:46:21 volumio systemd[4542]: Started mpris-proxy.service - Bluetooth mpris proxy. Oct 15 19:46:21 volumio systemd[4542]: Reached target default.target - Main User Target. Oct 15 19:46:21 volumio systemd[4542]: Startup finished in 870ms. Oct 15 19:46:21 volumio systemd[1]: Started session-c1.scope - Session c1 of User volumio. Oct 15 19:46:21 volumio mpris-proxy[4557]: Can't get on session bus Oct 15 19:46:21 volumio systemd[4542]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Oct 15 19:46:21 volumio systemd[4542]: mpris-proxy.service: Failed with result 'exit-code'. Oct 15 19:46:34 volumio su[4540]: pam_unix(su:session): session closed for user volumio Oct 15 19:46:34 volumio systemd[1]: session-c1.scope: Deactivated successfully. Oct 15 19:46:34 volumio systemd[1]: session-c1.scope: Consumed 21.106s CPU time. Oct 15 19:46:34 volumio volumio[1245]: info: Creating user directories (if not exist) Oct 15 19:46:34 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:34.390Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:34 volumio sudo[4537]: pam_unix(sudo:session): session closed for user root Oct 15 19:46:34 volumio volumio[1245]: info: Now Playing plugin installed Oct 15 19:46:34 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:34.436Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:34 volumio volumio[1245]: Plugin install end detected on script Oct 15 19:46:34 volumio volumio[1245]: info: Install script completed Oct 15 19:46:34 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:34.448Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:34 volumio volumio[1245]: info: Adding reference to registry Oct 15 19:46:34 volumio volumio5-onboarding[1858]: time=2026-10-15T17:46:34.456Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:46:34 volumio volumio[1245]: info: Done installing plugin. Oct 15 19:46:34 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 19:46:34 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 19:46:34 volumio volumio[1245]: info: Folder /tmp/plugins removed Oct 15 19:46:34 volumio volumio[1245]: info: Folder /tmp/downloaded_plugin.zip removed Oct 15 19:46:34 volumio volumio[1245]: info: Folder /data/temp removed Oct 15 19:46:39 volumio volumio[1245]: info: Enabling plugin now_playing Oct 15 19:46:39 volumio volumio[1245]: info: Loading plugin "now_playing"... Oct 15 19:46:39 volumio volumio[1245]: error: !!!! WARNING !!!! Oct 15 19:46:39 volumio volumio[1245]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Oct 15 19:46:39 volumio volumio[1245]: Require stack: Oct 15 19:46:39 volumio volumio[1245]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/app/pluginmanager.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/app/index.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/index.js Oct 15 19:46:39 volumio volumio[1245]: error: Stack trace: Error: Cannot find module 'geo-tz' Oct 15 19:46:39 volumio volumio[1245]: Require stack: Oct 15 19:46:39 volumio volumio[1245]: - /data/plugins/user_interface/now_playing/dist/index.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/app/pluginmanager.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/app/index.js Oct 15 19:46:39 volumio volumio[1245]: - /volumio/index.js Oct 15 19:46:39 volumio volumio[1245]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Oct 15 19:46:39 volumio volumio[1245]: at Module._load (node:internal/modules/cjs/loader:901:27) Oct 15 19:46:39 volumio volumio[1245]: at Module.require (node:internal/modules/cjs/loader:1115:19) Oct 15 19:46:39 volumio volumio[1245]: at require (node:internal/modules/helpers:130:18) Oct 15 19:46:39 volumio volumio[1245]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Oct 15 19:46:39 volumio volumio[1245]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Oct 15 19:46:39 volumio volumio[1245]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Oct 15 19:46:39 volumio volumio[1245]: at Module.load (node:internal/modules/cjs/loader:1091:32) Oct 15 19:46:39 volumio volumio[1245]: at Module._load (node:internal/modules/cjs/loader:938:12) Oct 15 19:46:39 volumio volumio[1245]: at Module.require (node:internal/modules/cjs/loader:1115:19) Oct 15 19:46:39 volumio volumio[1245]: at require (node:internal/modules/helpers:130:18) Oct 15 19:46:39 volumio volumio[1245]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Oct 15 19:46:39 volumio volumio[1245]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 15 19:46:39 volumio volumio[1245]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 15 19:46:39 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 15 19:46:39 volumio volumio[1245]: error: !!!! WARNING !!!! Oct 15 19:46:39 volumio volumio[1245]: info: Done. Oct 15 19:46:44 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Oct 15 19:46:44 volumio systemd[4542]: Activating special unit exit.target... Oct 15 19:46:44 volumio systemd[4542]: Stopped target default.target - Main User Target. Oct 15 19:46:44 volumio systemd[4542]: Stopped target basic.target - Basic System. Oct 15 19:46:44 volumio systemd[4542]: Stopped target paths.target - Paths. Oct 15 19:46:44 volumio systemd[4542]: Stopped target sockets.target - Sockets. Oct 15 19:46:44 volumio systemd[4542]: Stopped target timers.target - Timers. Oct 15 19:46:44 volumio systemd[4542]: Closed dirmngr.socket - GnuPG network certificate management daemon. Oct 15 19:46:44 volumio systemd[4542]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Oct 15 19:46:44 volumio systemd[4542]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Oct 15 19:46:44 volumio systemd[4542]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Oct 15 19:46:44 volumio systemd[4542]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Oct 15 19:46:44 volumio systemd[4542]: Removed slice app.slice - User Application Slice. Oct 15 19:46:44 volumio systemd[4542]: Reached target shutdown.target - Shutdown. Oct 15 19:46:44 volumio systemd[4542]: Finished systemd-exit.service - Exit the Session. Oct 15 19:46:44 volumio systemd[4542]: Reached target exit.target - Exit the Session. Oct 15 19:46:44 volumio (sd-pam)[4543]: pam_unix(systemd-user:session): session closed for user volumio Oct 15 19:46:44 volumio systemd[1]: user@1000.service: Deactivated successfully. Oct 15 19:46:44 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Oct 15 19:46:44 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Oct 15 19:46:44 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Oct 15 19:46:44 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Oct 15 19:46:44 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Oct 15 19:46:44 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Oct 15 19:46:44 volumio systemd[1]: user-1000.slice: Consumed 21.988s CPU time. Oct 15 19:46:52 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 19:46:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 19:46:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 19:47:04 volumio nmbd[1035]: [2026/10/15 19:47:04.420257, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 15 19:47:04 volumio nmbd[1035]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.153 for name WORKGROUP<1d>. Oct 15 19:47:04 volumio nmbd[1035]: This response was from IP 192.168.1.111, reporting an IP address of 192.168.1.111. Oct 15 19:47:04 volumio volumio[1245]: info: Starting Uninstall of plugin user_interface - now_playing Oct 15 19:47:04 volumio volumio[1245]: info: Uninstalling plugin now_playing Oct 15 19:47:04 volumio volumio5-onboarding[1858]: time=2026-10-15T17:47:04.797Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:47:04 volumio volumio5-onboarding[1858]: time=2026-10-15T17:47:04.806Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:47:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 19:47:15 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Oct 15 19:47:15 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 19:47:15 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 15 19:47:17 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 15 19:47:17 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 15 19:47:17 volumio systemd[1]: setdatetime-helper.service: Consumed 1.460s CPU time. Oct 15 19:47:24 volumio volumio[1245]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/stylish_player/volumio/bookworm/armhf Oct 15 19:47:24 volumio volumio5-onboarding[1858]: time=2026-10-15T17:47:24.291Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 15 19:47:24 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 19:47:24 volumio volumio[1245]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Oct 15 19:47:24 volumio volumio[1245]: at /volumio/app/index.js:1534:32 Oct 15 19:47:24 volumio volumio[1245]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 15 19:47:24 volumio volumio[1245]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 15 19:47:24 volumio volumio[1245]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Oct 15 19:47:24 volumio volumio[1245]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Oct 15 19:47:24 volumio volumio[1245]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Oct 15 19:47:24 volumio volumio[1245]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) Oct 15 19:47:24 volumio volumio[1245]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1220:47) Oct 15 19:47:24 volumio volumio[1245]: at Socket.emit (node:events:514:28) Oct 15 19:47:24 volumio volumio[1245]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 15 19:47:24 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Oct 15 19:47:24 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 19:47:25 volumio sudo[4733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 19:46' Oct 15 19:47:25 volumio sudo[4733]: 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"