Feb 11 18:18:04 volumio volumio[2737]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf Feb 11 18:18:04 volumio volumio[2737]: info: Folder /tmp/plugins removed Feb 11 18:18:13 volumio volumio[2737]: info: Check plugin dependencies Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:18:16 volumio volumio[2737]: info: Checking if plugin already exists Feb 11 18:18:16 volumio volumio[2737]: info: Rename folder Feb 11 18:18:16 volumio volumio[2737]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:18:16 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:18:16 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 11 18:18:16 volumio volumio[2737]: info: Move to category Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 11 18:18:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 11 18:18:22 volumio sudo[4006]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 11 18:18:22 volumio sudo[4025]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:18:22 volumio sudo[4027]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:18:22 volumio sudo[4025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4020]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 18:18:22 volumio sudo[4025]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:22 volumio sudo[4020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4027]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:22 volumio sudo[4016]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 18:18:22 volumio sudo[4016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4009]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 11 18:18:22 volumio sudo[4009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:18:22 volumio sudo[4006]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:22 volumio sudo[4016]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:22 volumio sudo[4009]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:22 volumio sudo[4020]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:23 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:18:23 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:18:26 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:18:26 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:18:26 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:18:26 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:18:26 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:18:26 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.5.190:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:18:26 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:18:34 volumio su[3898]: pam_unix(su:session): session closed for user volumio Feb 11 18:18:34 volumio systemd[1]: session-c1.scope: Deactivated successfully. Feb 11 18:18:34 volumio systemd[1]: session-c1.scope: Consumed 1min 12.438s CPU time. Feb 11 18:18:35 volumio sudo[3894]: pam_unix(sudo:session): session closed for user root Feb 11 18:18:41 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 18:18:44 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Feb 11 18:18:44 volumio systemd[3900]: Activating special unit exit.target... Feb 11 18:18:44 volumio systemd[3900]: Stopped target default.target - Main User Target. Feb 11 18:18:44 volumio systemd[3900]: Stopped target basic.target - Basic System. Feb 11 18:18:44 volumio systemd[3900]: Stopped target paths.target - Paths. Feb 11 18:18:44 volumio systemd[3900]: Stopped target sockets.target - Sockets. Feb 11 18:18:44 volumio systemd[3900]: Stopped target timers.target - Timers. Feb 11 18:18:44 volumio systemd[3900]: Closed dirmngr.socket - GnuPG network certificate management daemon. Feb 11 18:18:44 volumio systemd[3900]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 11 18:18:44 volumio systemd[3900]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 11 18:18:44 volumio systemd[3900]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 11 18:18:44 volumio systemd[3900]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 11 18:18:44 volumio systemd[3900]: Removed slice app.slice - User Application Slice. Feb 11 18:18:44 volumio systemd[3900]: Reached target shutdown.target - Shutdown. Feb 11 18:18:44 volumio systemd[3900]: Finished systemd-exit.service - Exit the Session. Feb 11 18:18:44 volumio systemd[3900]: Reached target exit.target - Exit the Session. Feb 11 18:18:44 volumio systemd[1]: user@1000.service: Deactivated successfully. Feb 11 18:18:44 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Feb 11 18:18:45 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 11 18:18:45 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Feb 11 18:18:45 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Feb 11 18:18:45 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 11 18:18:45 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Feb 11 18:18:45 volumio systemd[1]: user-1000.slice: Consumed 1min 13.155s CPU time. Feb 11 18:18:58 volumio ntpd[969]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 11 18:19:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Feb 11 18:19:10 volumio volumio[2737]: info: Checking if install.sh is present Feb 11 18:19:10 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:19:10 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:19:10 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 11 18:19:10 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 11 18:19:10 volumio volumio[2737]: info: Executing install.sh Feb 11 18:19:11 volumio volumio[2737]: info: Install script completed Feb 11 18:19:11 volumio volumio[2737]: info: Adding reference to registry Feb 11 18:19:11 volumio sudo[4166]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Feb 11 18:19:11 volumio sudo[4166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:11 volumio volumio[2737]: info: Done installing plugin. Feb 11 18:19:11 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 18:19:11 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 11 18:19:11 volumio su[4169]: (to volumio) root on none Feb 11 18:19:11 volumio su[4169]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Feb 11 18:19:11 volumio volumio[2737]: info: Feb 11 18:19:11 volumio volumio[2737]: info: added 27 packages, changed 4 packages, and audited 162 packages in 50s Feb 11 18:19:11 volumio volumio[2737]: info: Feb 11 18:19:11 volumio volumio[2737]: info: 24 packages are looking for funding Feb 11 18:19:11 volumio volumio[2737]: info: run `npm fund` for details Feb 11 18:19:11 volumio volumio[2737]: info: Feb 11 18:19:11 volumio volumio[2737]: info: found 0 vulnerabilities Feb 11 18:19:11 volumio volumio[2737]: info: Creating user directories (if not exist) Feb 11 18:19:11 volumio volumio[2737]: info: Now Playing plugin installed Feb 11 18:19:11 volumio volumio[2737]: Plugin install end detected on script Feb 11 18:19:11 volumio volumio[2737]: info: Folder /tmp/plugins removed Feb 11 18:19:11 volumio volumio[2737]: info: Folder /tmp/downloaded_plugin.zip removed Feb 11 18:19:11 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Feb 11 18:19:11 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 11 18:19:11 volumio volumio[2737]: info: Folder /data/temp removed Feb 11 18:19:11 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 11 18:19:11 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Feb 11 18:19:11 volumio (systemd)[4171]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Feb 11 18:19:12 volumio systemd[4171]: Queued start job for default target default.target. Feb 11 18:19:12 volumio systemd[4171]: Created slice app.slice - User Application Slice. Feb 11 18:19:12 volumio systemd[4171]: Reached target paths.target - Paths. Feb 11 18:19:12 volumio systemd[4171]: Reached target timers.target - Timers. Feb 11 18:19:12 volumio systemd[4171]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Feb 11 18:19:12 volumio systemd[4171]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 11 18:19:12 volumio systemd[4171]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 11 18:19:12 volumio systemd[4171]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 11 18:19:12 volumio systemd[4171]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 11 18:19:12 volumio systemd[4171]: Reached target sockets.target - Sockets. Feb 11 18:19:12 volumio systemd[4171]: Reached target basic.target - Basic System. Feb 11 18:19:12 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Feb 11 18:19:12 volumio systemd[4171]: Started mpris-proxy.service - Bluetooth mpris proxy. Feb 11 18:19:12 volumio systemd[4171]: Reached target default.target - Main User Target. Feb 11 18:19:12 volumio systemd[4171]: Startup finished in 1.322s. Feb 11 18:19:12 volumio systemd[1]: Started session-c2.scope - Session c2 of User volumio. Feb 11 18:19:12 volumio mpris-proxy[4186]: Can't get on session bus Feb 11 18:19:12 volumio systemd[4171]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Feb 11 18:19:12 volumio systemd[4171]: mpris-proxy.service: Failed with result 'exit-code'. Feb 11 18:19:13 volumio volumio[2737]: info: Enabling plugin now_playing Feb 11 18:19:13 volumio volumio[2737]: info: Loading plugin "now_playing"... Feb 11 18:19:13 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:13 volumio volumio[2737]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 11 18:19:13 volumio volumio[2737]: Require stack: Feb 11 18:19:13 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:13 volumio volumio[2737]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 11 18:19:13 volumio volumio[2737]: Require stack: Feb 11 18:19:13 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:13 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:13 volumio volumio[2737]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 11 18:19:13 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 11 18:19:13 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:13 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:13 volumio volumio[2737]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 11 18:19:13 volumio volumio[2737]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 11 18:19:13 volumio volumio[2737]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 11 18:19:13 volumio volumio[2737]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 11 18:19:13 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 11 18:19:13 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:13 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:13 volumio volumio[2737]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 11 18:19:13 volumio volumio[2737]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 11 18:19:13 volumio volumio[2737]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 11 18:19:13 volumio volumio[2737]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 11 18:19:13 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:13 volumio volumio[2737]: info: Done. Feb 11 18:19:14 volumio sudo[4198]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:19:14 volumio sudo[4198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:14 volumio sudo[4198]: pam_unix(sudo:session): session closed for user root Feb 11 18:19:14 volumio sudo[4200]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:19:14 volumio sudo[4200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:14 volumio sudo[4200]: pam_unix(sudo:session): session closed for user root Feb 11 18:19:14 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 11 18:19:15 volumio sudo[4204]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 11 18:19:15 volumio sudo[4204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:15 volumio sudo[4204]: pam_unix(sudo:session): session closed for user root Feb 11 18:19:15 volumio sudo[4206]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 11 18:19:15 volumio sudo[4206]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:15 volumio sudo[4206]: pam_unix(sudo:session): session closed for user root Feb 11 18:19:15 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.5.190 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::volumioGetVisibleSources Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:16 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 11 18:19:16 volumio volumio[2737]: info: Received Get System Info Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:19:16 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:16 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:16 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:16 volumio volumio[2737]: info: Listing playlists Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 18:19:16 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 11 18:19:17 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 18:19:17 volumio volumio[2737]: info: Received Get System Info Feb 11 18:19:17 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:19:17 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:19:17 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:19:17 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:17 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:17 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 11 18:19:18 volumio volumio[2737]: info: Received Get System Info Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:19:18 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:18 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:18 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:19:25 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 11 18:19:26 volumio volumio[2737]: info: Enabling plugin now_playing Feb 11 18:19:26 volumio volumio[2737]: info: Loading plugin "now_playing"... Feb 11 18:19:26 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:26 volumio volumio[2737]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 11 18:19:26 volumio volumio[2737]: Require stack: Feb 11 18:19:26 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:26 volumio volumio[2737]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 11 18:19:26 volumio volumio[2737]: Require stack: Feb 11 18:19:26 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:26 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:26 volumio volumio[2737]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 11 18:19:26 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 11 18:19:26 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:26 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:26 volumio volumio[2737]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 11 18:19:26 volumio volumio[2737]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 11 18:19:26 volumio volumio[2737]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 11 18:19:26 volumio volumio[2737]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 11 18:19:26 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 11 18:19:26 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:26 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:26 volumio volumio[2737]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 11 18:19:26 volumio volumio[2737]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 11 18:19:26 volumio volumio[2737]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 11 18:19:26 volumio volumio[2737]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 11 18:19:26 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:26 volumio volumio[2737]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 11 18:19:32 volumio volumio[2737]: info: Enabling plugin now_playing Feb 11 18:19:32 volumio volumio[2737]: info: Loading plugin "now_playing"... Feb 11 18:19:32 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:32 volumio volumio[2737]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 11 18:19:32 volumio volumio[2737]: Require stack: Feb 11 18:19:32 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:32 volumio volumio[2737]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 11 18:19:32 volumio volumio[2737]: Require stack: Feb 11 18:19:32 volumio volumio[2737]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/app/pluginmanager.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/app/index.js Feb 11 18:19:32 volumio volumio[2737]: - /volumio/index.js Feb 11 18:19:32 volumio volumio[2737]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 11 18:19:32 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 11 18:19:32 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:32 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:32 volumio volumio[2737]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Feb 11 18:19:32 volumio volumio[2737]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 11 18:19:32 volumio volumio[2737]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 11 18:19:32 volumio volumio[2737]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 11 18:19:32 volumio volumio[2737]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 11 18:19:32 volumio volumio[2737]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 11 18:19:32 volumio volumio[2737]: at require (node:internal/modules/helpers:130:18) Feb 11 18:19:32 volumio volumio[2737]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 11 18:19:32 volumio volumio[2737]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 11 18:19:32 volumio volumio[2737]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 11 18:19:32 volumio volumio[2737]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 11 18:19:32 volumio volumio[2737]: error: !!!! WARNING !!!! Feb 11 18:19:32 volumio volumio[2737]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 11 18:19:34 volumio volumio[2737]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 11 18:19:34 volumio volumio[2737]: error: Failed callmethod call: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 11 18:19:34 volumio volumio[2737]: info: Received Get System Version Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 11 18:19:34 volumio volumio[2737]: info: Received Get System Info Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 11 18:19:34 volumio volumio[2737]: info: Discovery: Getting this device information Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Feb 11 18:19:34 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Feb 11 18:19:34 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 11 18:19:36 volumio volumio[2737]: info: Feb 11 18:19:36 volumio volumio[2737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:19:36 volumio volumio[2737]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 11 18:19:36 volumio volumio[2737]: at /volumio/app/index.js:1534:32 Feb 11 18:19:36 volumio volumio[2737]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 11 18:19:36 volumio volumio[2737]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 11 18:19:36 volumio volumio[2737]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Feb 11 18:19:36 volumio volumio[2737]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Feb 11 18:19:36 volumio volumio[2737]: at Tail. (/volumio/app/pluginmanager.js:860:24) Feb 11 18:19:36 volumio volumio[2737]: at Tail.emit (node:events:514:28) Feb 11 18:19:36 volumio volumio[2737]: at ReadStream. (/volumio/node_modules/tail/lib/tail.js:51:33) Feb 11 18:19:36 volumio volumio[2737]: at ReadStream.emit (node:events:514:28) Feb 11 18:19:36 volumio volumio[2737]: at addChunk (node:internal/streams/readable:343:12) Feb 11 18:19:36 volumio volumio[2737]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 11 18:19:36 volumio volumio[2737]: at Readable.push (node:internal/streams/readable:253:10) Feb 11 18:19:36 volumio volumio[2737]: at node:internal/fs/streams:275:14 Feb 11 18:19:36 volumio volumio[2737]: at FSReqCallback.wrapper [as oncomplete] (node:fs:701:5) Feb 11 18:19:36 volumio volumio[2737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 11 18:19:37 volumio su[4169]: pam_unix(su:session): session closed for user volumio Feb 11 18:19:37 volumio systemd[1]: session-c2.scope: Deactivated successfully. Feb 11 18:19:37 volumio systemd[1]: session-c2.scope: Consumed 31.743s CPU time. Feb 11 18:19:37 volumio sudo[4166]: pam_unix(sudo:session): session closed for user root Feb 11 18:19:41 volumio kernel: hwmon hwmon1: Voltage normalised Feb 11 18:19:47 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Feb 11 18:19:47 volumio systemd[4171]: Activating special unit exit.target... Feb 11 18:19:47 volumio systemd[4171]: Stopped target default.target - Main User Target. Feb 11 18:19:47 volumio systemd[4171]: Stopped target basic.target - Basic System. Feb 11 18:19:47 volumio systemd[4171]: Stopped target paths.target - Paths. Feb 11 18:19:47 volumio systemd[4171]: Stopped target sockets.target - Sockets. Feb 11 18:19:47 volumio systemd[4171]: Stopped target timers.target - Timers. Feb 11 18:19:47 volumio systemd[4171]: Closed dirmngr.socket - GnuPG network certificate management daemon. Feb 11 18:19:47 volumio systemd[4171]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Feb 11 18:19:47 volumio systemd[4171]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Feb 11 18:19:47 volumio systemd[4171]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Feb 11 18:19:47 volumio systemd[4171]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Feb 11 18:19:47 volumio systemd[4171]: Removed slice app.slice - User Application Slice. Feb 11 18:19:47 volumio systemd[4171]: Reached target shutdown.target - Shutdown. Feb 11 18:19:47 volumio systemd[4171]: Finished systemd-exit.service - Exit the Session. Feb 11 18:19:47 volumio systemd[4171]: Reached target exit.target - Exit the Session. Feb 11 18:19:47 volumio systemd[1]: user@1000.service: Deactivated successfully. Feb 11 18:19:47 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Feb 11 18:19:47 volumio systemd[1]: user@1000.service: Consumed 1.363s CPU time. Feb 11 18:19:47 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Feb 11 18:19:47 volumio sudo[4310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-11 18:18' Feb 11 18:19:47 volumio sudo[4310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 11 18:19:49 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Feb 11 18:19:49 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Feb 11 18:19:49 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Feb 11 18:19:49 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Feb 11 18:19:49 volumio systemd[1]: user-1000.slice: Consumed 33.198s CPU time. 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"