Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.027+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio volumio[963]: info: Check plugin dependencies Mar 27 20:37:00 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.062+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio volumio[963]: info: Checking if plugin already exists Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.064+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio volumio[963]: info: Rename folder Mar 27 20:37:00 volumio volumio[963]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.081+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio volumio[963]: info: Move to category Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.326+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio volumio[963]: info: Checking if install.sh is present Mar 27 20:37:00 volumio volumio[963]: info: Executing install.sh Mar 27 20:37:00 volumio sudo[2849]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Mar 27 20:37:00 volumio sudo[2849]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 20:37:00 volumio volumio[963]: info: Installing geo-tz node dependency Mar 27 20:37:00 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:00.380+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:00 volumio su[2852]: (to volumio) root on none Mar 27 20:37:00 volumio su[2852]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Mar 27 20:37:00 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Mar 27 20:37:00 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Mar 27 20:37:00 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Mar 27 20:37:00 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Mar 27 20:37:00 volumio (systemd)[2854]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Mar 27 20:37:00 volumio systemd[2854]: Queued start job for default target default.target. Mar 27 20:37:00 volumio systemd[2854]: Created slice app.slice - User Application Slice. Mar 27 20:37:00 volumio systemd[2854]: Reached target paths.target - Paths. Mar 27 20:37:00 volumio systemd[2854]: Reached target timers.target - Timers. Mar 27 20:37:00 volumio systemd[2854]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 27 20:37:00 volumio systemd[2854]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Mar 27 20:37:00 volumio systemd[2854]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 27 20:37:00 volumio systemd[2854]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 27 20:37:00 volumio systemd[2854]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 27 20:37:00 volumio systemd[2854]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 27 20:37:00 volumio systemd[2854]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 27 20:37:00 volumio systemd[2854]: Reached target sockets.target - Sockets. Mar 27 20:37:00 volumio systemd[2854]: Reached target basic.target - Basic System. Mar 27 20:37:00 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Mar 27 20:37:00 volumio systemd[1]: Started session-c1.scope - Session c1 of User volumio. Mar 27 20:37:00 volumio systemd[2854]: Started mpris-proxy.service - Bluetooth mpris proxy. Mar 27 20:37:00 volumio systemd[2854]: Reached target default.target - Main User Target. Mar 27 20:37:00 volumio systemd[2854]: Startup finished in 140ms. Mar 27 20:37:00 volumio systemd[2854]: Created slice session.slice - User Core Session Slice. Mar 27 20:37:00 volumio systemd[2854]: Starting dbus.service - D-Bus User Message Bus... Mar 27 20:37:00 volumio systemd[2854]: Started dbus.service - D-Bus User Message Bus. Mar 27 20:37:05 volumio su[2852]: pam_unix(su:session): session closed for user volumio Mar 27 20:37:05 volumio systemd[1]: session-c1.scope: Deactivated successfully. Mar 27 20:37:05 volumio systemd[1]: session-c1.scope: Consumed 6.502s CPU time. Mar 27 20:37:05 volumio volumio[963]: info: Creating user directories (if not exist) Mar 27 20:37:05 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:05.294+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:05 volumio sudo[2849]: pam_unix(sudo:session): session closed for user root Mar 27 20:37:05 volumio volumio[963]: info: Now Playing plugin installed Mar 27 20:37:05 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:05.300+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:05 volumio volumio[963]: Plugin install end detected on script Mar 27 20:37:05 volumio volumio[963]: info: Install script completed Mar 27 20:37:05 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:05.304+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:05 volumio volumio[963]: info: Adding reference to registry Mar 27 20:37:05 volumio volumio5-onboarding[1317]: time=2026-03-27T20:37:05.306+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:37:05 volumio volumio[963]: info: Done installing plugin. Mar 27 20:37:05 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:05 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 20:37:05 volumio volumio[963]: info: Folder /tmp/plugins removed Mar 27 20:37:05 volumio volumio[963]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 20:37:05 volumio volumio[963]: info: Folder /data/temp removed Mar 27 20:37:08 volumio volumio[963]: info: Enabling plugin now_playing Mar 27 20:37:08 volumio volumio[963]: info: Loading plugin "now_playing"... Mar 27 20:37:08 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:08 volumio volumio[963]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 27 20:37:08 volumio volumio[963]: Require stack: Mar 27 20:37:08 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:08 volumio volumio[963]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 27 20:37:08 volumio volumio[963]: Require stack: Mar 27 20:37:08 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:08 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:08 volumio volumio[963]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Mar 27 20:37:08 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:901:27) Mar 27 20:37:08 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:08 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:08 volumio volumio[963]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Mar 27 20:37:08 volumio volumio[963]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Mar 27 20:37:08 volumio volumio[963]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Mar 27 20:37:08 volumio volumio[963]: at Module.load (node:internal/modules/cjs/loader:1091:32) Mar 27 20:37:08 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:938:12) Mar 27 20:37:08 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:08 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:08 volumio volumio[963]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 27 20:37:08 volumio volumio[963]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 27 20:37:08 volumio volumio[963]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 27 20:37:08 volumio volumio[963]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 27 20:37:08 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:08 volumio volumio[963]: info: Done. Mar 27 20:37:15 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Mar 27 20:37:15 volumio systemd[2854]: Activating special unit exit.target... Mar 27 20:37:15 volumio systemd[2854]: Stopped target default.target - Main User Target. Mar 27 20:37:15 volumio systemd[2854]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Mar 27 20:37:15 volumio mpris-proxy[2870]: org.bluez appeared Mar 27 20:37:15 volumio mpris-proxy[2870]: org.bluez disappeared Mar 27 20:37:15 volumio systemd[2854]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Mar 27 20:37:15 volumio systemd[2854]: Stopping dbus.service - D-Bus User Message Bus... Mar 27 20:37:15 volumio systemd[2854]: Stopped dbus.service - D-Bus User Message Bus. Mar 27 20:37:15 volumio systemd[2854]: Removed slice session.slice - User Core Session Slice. Mar 27 20:37:15 volumio systemd[2854]: Stopped target basic.target - Basic System. Mar 27 20:37:15 volumio systemd[2854]: Stopped target paths.target - Paths. Mar 27 20:37:15 volumio systemd[2854]: Stopped target sockets.target - Sockets. Mar 27 20:37:15 volumio systemd[2854]: Stopped target timers.target - Timers. Mar 27 20:37:15 volumio systemd[2854]: Closed dbus.socket - D-Bus User Message Bus Socket. Mar 27 20:37:15 volumio systemd[2854]: Closed dirmngr.socket - GnuPG network certificate management daemon. Mar 27 20:37:15 volumio systemd[2854]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 27 20:37:15 volumio systemd[2854]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 27 20:37:15 volumio systemd[2854]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 27 20:37:15 volumio systemd[2854]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 27 20:37:15 volumio systemd[2854]: Removed slice app.slice - User Application Slice. Mar 27 20:37:15 volumio systemd[2854]: Reached target shutdown.target - Shutdown. Mar 27 20:37:15 volumio systemd[2854]: Finished systemd-exit.service - Exit the Session. Mar 27 20:37:15 volumio systemd[2854]: Reached target exit.target - Exit the Session. Mar 27 20:37:15 volumio systemd[1]: user@1000.service: Deactivated successfully. Mar 27 20:37:15 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Mar 27 20:37:15 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Mar 27 20:37:15 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Mar 27 20:37:15 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Mar 27 20:37:15 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Mar 27 20:37:15 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Mar 27 20:37:15 volumio systemd[1]: user-1000.slice: Consumed 6.697s CPU time. Mar 27 20:37:26 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 20:37:29 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:29 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 20:37:31 volumio sudo[2985]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 20:37:31 volumio sudo[2985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 20:37:31 volumio sudo[2985]: pam_unix(sudo:session): session closed for user root Mar 27 20:37:31 volumio sudo[2987]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 20:37:31 volumio sudo[2987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 20:37:31 volumio sudo[2987]: pam_unix(sudo:session): session closed for user root Mar 27 20:37:31 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.178.123 from 192.168.178.119 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 20:37:31 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:31 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:31 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 20:37:31 volumio volumio[963]: info: Listing playlists Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 20:37:31 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:33 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:33 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:33 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:33 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:34 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:34 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:34 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:34 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:34 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:34 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:34 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:34 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:34 volumio volumio[963]: info: Enabling plugin now_playing Mar 27 20:37:34 volumio volumio[963]: info: Loading plugin "now_playing"... Mar 27 20:37:34 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:34 volumio volumio[963]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 27 20:37:34 volumio volumio[963]: Require stack: Mar 27 20:37:34 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:34 volumio volumio[963]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 27 20:37:34 volumio volumio[963]: Require stack: Mar 27 20:37:34 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:34 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:34 volumio volumio[963]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Mar 27 20:37:34 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:901:27) Mar 27 20:37:34 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:34 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:34 volumio volumio[963]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Mar 27 20:37:34 volumio volumio[963]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Mar 27 20:37:34 volumio volumio[963]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Mar 27 20:37:34 volumio volumio[963]: at Module.load (node:internal/modules/cjs/loader:1091:32) Mar 27 20:37:34 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:938:12) Mar 27 20:37:34 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:34 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:34 volumio volumio[963]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 27 20:37:34 volumio volumio[963]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 27 20:37:34 volumio volumio[963]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 27 20:37:34 volumio volumio[963]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 27 20:37:34 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:34 volumio volumio[963]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Mar 27 20:37:36 volumio volumio[963]: info: Enabling plugin now_playing Mar 27 20:37:36 volumio volumio[963]: info: Loading plugin "now_playing"... Mar 27 20:37:36 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:36 volumio volumio[963]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 27 20:37:36 volumio volumio[963]: Require stack: Mar 27 20:37:36 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:36 volumio volumio[963]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 27 20:37:36 volumio volumio[963]: Require stack: Mar 27 20:37:36 volumio volumio[963]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/app/pluginmanager.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/app/index.js Mar 27 20:37:36 volumio volumio[963]: - /volumio/index.js Mar 27 20:37:36 volumio volumio[963]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Mar 27 20:37:36 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:901:27) Mar 27 20:37:36 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:36 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:36 volumio volumio[963]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Mar 27 20:37:36 volumio volumio[963]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Mar 27 20:37:36 volumio volumio[963]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Mar 27 20:37:36 volumio volumio[963]: at Module.load (node:internal/modules/cjs/loader:1091:32) Mar 27 20:37:36 volumio volumio[963]: at Module._load (node:internal/modules/cjs/loader:938:12) Mar 27 20:37:36 volumio volumio[963]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 27 20:37:36 volumio volumio[963]: at require (node:internal/modules/helpers:130:18) Mar 27 20:37:36 volumio volumio[963]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 27 20:37:36 volumio volumio[963]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 27 20:37:36 volumio volumio[963]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 27 20:37:36 volumio volumio[963]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 27 20:37:36 volumio volumio[963]: error: !!!! WARNING !!!! Mar 27 20:37:36 volumio volumio[963]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Mar 27 20:37:37 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 20:37:41 volumio sudo[3017]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 27 20:37:41 volumio sudo[3017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 20:37:41 volumio sudo[3017]: pam_unix(sudo:session): session closed for user root Mar 27 20:37:41 volumio sudo[3020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 27 20:37:41 volumio sudo[3020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 27 20:37:41 volumio sudo[3020]: pam_unix(sudo:session): session closed for user root Mar 27 20:37:41 volumio volumio[963]: verbose: New Socket.io Connection to 192.168.178.123 from 192.168.178.119 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:41 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 20:37:41 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:41 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:41 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:41 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:41 volumio volumio[963]: info: Listing playlists Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 20:37:41 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 20:37:42 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 27 20:37:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:43 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:43 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:43 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:43 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:43 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:44 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:44 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:44 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:44 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:44 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:37:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 20:37:46 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 20:37:51 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 27 20:37:55 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 27 20:37:59 volumio volumio[963]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 27 20:37:59 volumio volumio[963]: error: Failed callmethod call: TypeError: Cannot read properties of null (reading 'broadcastMessage') Mar 27 20:37:59 volumio volumio[963]: info: Received Get System Version Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 27 20:37:59 volumio volumio[963]: info: Received Get System Info Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 20:37:59 volumio volumio[963]: info: Discovery: Getting this device information Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::volumioGetState Mar 27 20:37:59 volumio volumio[963]: info: CorePlayQueue::getTrack 0 Mar 27 20:37:59 volumio volumio[963]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 20:38:07 volumio volumio[963]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/download/now_playing/1.0.6/volumio/bookworm/amd64 Mar 27 20:38:07 volumio volumio5-onboarding[1317]: time=2026-03-27T20:38:07.511+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 20:38:07 volumio volumio[963]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 20:38:07 volumio volumio[963]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Mar 27 20:38:07 volumio volumio[963]: at /volumio/app/index.js:1534:32 Mar 27 20:38:07 volumio volumio[963]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 27 20:38:07 volumio volumio[963]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 27 20:38:07 volumio volumio[963]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Mar 27 20:38:07 volumio volumio[963]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Mar 27 20:38:07 volumio volumio[963]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Mar 27 20:38:07 volumio volumio[963]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) Mar 27 20:38:07 volumio volumio[963]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1220:47) Mar 27 20:38:07 volumio volumio[963]: at Socket.emit (node:events:514:28) Mar 27 20:38:07 volumio volumio[963]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Mar 27 20:38:07 volumio volumio[963]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 27 20:38:07 volumio volumio[963]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 20:38:07 volumio sudo[3097]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-27 20:37' Mar 27 20:38:07 volumio sudo[3097]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"