May 31 22:33:05 bedroomplayer2 wpa_supplicant[1182]: wlan0: WPA: Group rekeying completed with 04:ab:08:d2:5d:e3 [GTK=CCMP] May 31 22:33:14 bedroomplayer2 volumio[6036]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/bookworm/armhf May 31 22:33:14 bedroomplayer2 volumio[6036]: info: Folder /tmp/plugins removed May 31 22:33:15 bedroomplayer2 volumio[6036]: info: Check plugin dependencies May 31 22:33:15 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 22:33:15 bedroomplayer2 volumio[6036]: info: Checking if plugin already exists May 31 22:33:15 bedroomplayer2 volumio[6036]: info: Rename folder May 31 22:33:15 bedroomplayer2 volumio[6036]: info: Folder /tmp/downloaded_plugin.zip removed May 31 22:33:15 bedroomplayer2 volumio[6036]: info: Move to category May 31 22:33:17 bedroomplayer2 volumio[6036]: info: Checking if install.sh is present May 31 22:33:17 bedroomplayer2 volumio[6036]: info: Executing install.sh May 31 22:33:17 bedroomplayer2 sudo[10166]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh May 31 22:33:17 bedroomplayer2 sudo[10166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 31 22:33:17 bedroomplayer2 volumio[6036]: info: Installing geo-tz node dependency May 31 22:33:26 bedroomplayer2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Creating user directories (if not exist) May 31 22:33:51 bedroomplayer2 sudo[10166]: pam_unix(sudo:session): session closed for user root May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Now Playing plugin installed May 31 22:33:51 bedroomplayer2 volumio[6036]: Plugin install end detected on script May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Install script completed May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Adding reference to registry May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Done installing plugin. May 31 22:33:51 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 22:33:51 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Folder /tmp/plugins removed May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Folder /tmp/downloaded_plugin.zip removed May 31 22:33:51 bedroomplayer2 volumio[6036]: info: Folder /data/temp removed May 31 22:34:35 bedroomplayer2 volumio[6036]: info: Enabling plugin now_playing May 31 22:34:35 bedroomplayer2 volumio[6036]: info: Loading plugin "now_playing"... May 31 22:34:35 bedroomplayer2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 31 22:34:35 bedroomplayer2 volumio[6036]: error: !!!! WARNING !!!! May 31 22:34:35 bedroomplayer2 volumio[6036]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' May 31 22:34:35 bedroomplayer2 volumio[6036]: Require stack: May 31 22:34:35 bedroomplayer2 volumio[6036]: - /data/plugins/user_interface/now_playing/dist/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/app/pluginmanager.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/app/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: error: Stack trace: Error: Cannot find module 'geo-tz' May 31 22:34:35 bedroomplayer2 volumio[6036]: Require stack: May 31 22:34:35 bedroomplayer2 volumio[6036]: - /data/plugins/user_interface/now_playing/dist/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/app/pluginmanager.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/app/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: - /volumio/index.js May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module._load (node:internal/modules/cjs/loader:901:27) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module.require (node:internal/modules/cjs/loader:1115:19) May 31 22:34:35 bedroomplayer2 volumio[6036]: at require (node:internal/modules/helpers:130:18) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module._compile (node:internal/modules/cjs/loader:1233:14) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module.load (node:internal/modules/cjs/loader:1091:32) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module._load (node:internal/modules/cjs/loader:938:12) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Module.require (node:internal/modules/cjs/loader:1115:19) May 31 22:34:35 bedroomplayer2 volumio[6036]: at require (node:internal/modules/helpers:130:18) May 31 22:34:35 bedroomplayer2 volumio[6036]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) May 31 22:34:35 bedroomplayer2 volumio[6036]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 31 22:34:35 bedroomplayer2 volumio[6036]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 22:34:35 bedroomplayer2 volumio[6036]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 31 22:34:35 bedroomplayer2 volumio[6036]: error: !!!! WARNING !!!! May 31 22:34:35 bedroomplayer2 volumio[6036]: info: Done. May 31 22:34:35 bedroomplayer2 dbus-daemon[669]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.122' (uid=0 pid=10515 comm="timedatectl show --property=NTPSynchronized --valu") May 31 22:34:35 bedroomplayer2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 31 22:34:36 bedroomplayer2 dbus-daemon[669]: [system] Successfully activated service 'org.freedesktop.timedate1' May 31 22:34:36 bedroomplayer2 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 31 22:34:36 bedroomplayer2 setdatetime-helper.sh[10514]: Time is already synchronized. May 31 22:34:36 bedroomplayer2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 31 22:34:36 bedroomplayer2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 31 22:34:42 bedroomplayer2 volumio[6036]: info: Enabling plugin now_playing May 31 22:34:42 bedroomplayer2 volumio[6036]: info: Loading plugin "now_playing"... May 31 22:34:42 bedroomplayer2 volumio[6036]: error: !!!! WARNING !!!! May 31 22:34:42 bedroomplayer2 volumio[6036]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' May 31 22:34:42 bedroomplayer2 volumio[6036]: Require stack: May 31 22:34:42 bedroomplayer2 volumio[6036]: - /data/plugins/user_interface/now_playing/dist/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/app/pluginmanager.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/app/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: error: Stack trace: Error: Cannot find module 'geo-tz' May 31 22:34:42 bedroomplayer2 volumio[6036]: Require stack: May 31 22:34:42 bedroomplayer2 volumio[6036]: - /data/plugins/user_interface/now_playing/dist/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/app/pluginmanager.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/app/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: - /volumio/index.js May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module._load (node:internal/modules/cjs/loader:901:27) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module.require (node:internal/modules/cjs/loader:1115:19) May 31 22:34:42 bedroomplayer2 volumio[6036]: at require (node:internal/modules/helpers:130:18) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module._compile (node:internal/modules/cjs/loader:1233:14) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module.load (node:internal/modules/cjs/loader:1091:32) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module._load (node:internal/modules/cjs/loader:938:12) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Module.require (node:internal/modules/cjs/loader:1115:19) May 31 22:34:42 bedroomplayer2 volumio[6036]: at require (node:internal/modules/helpers:130:18) May 31 22:34:42 bedroomplayer2 volumio[6036]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) May 31 22:34:42 bedroomplayer2 volumio[6036]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 31 22:34:42 bedroomplayer2 volumio[6036]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 31 22:34:42 bedroomplayer2 volumio[6036]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 31 22:34:42 bedroomplayer2 volumio[6036]: error: !!!! WARNING !!!! May 31 22:34:42 bedroomplayer2 volumio[6036]: info: Error: TypeError: Cannot read properties of null (reading 'printToastMessage') May 31 22:34:48 bedroomplayer2 volumio[6036]: info: CorePlayQueue::getTrack 0 May 31 22:34:48 bedroomplayer2 volumio[6036]: info: CorePlayQueue::getTrack 1 May 31 22:34:48 bedroomplayer2 volumio[6036]: info: Prefetching next song May 31 22:34:50 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 22:34:50 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 22:34:52 bedroomplayer2 volumio[6036]: info: CoreStateMachine::startPlaybackTimer May 31 22:34:52 bedroomplayer2 volumio[6036]: info: CorePlayQueue::getTrack 1 May 31 22:34:53 bedroomplayer2 volumio[6036]: info: CoreStateMachine::pushState May 31 22:34:53 bedroomplayer2 volumio[6036]: info: CorePlayQueue::getTrack 1 May 31 22:34:53 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 22:34:53 bedroomplayer2 volumio[6036]: info: CoreCommandRouter::volumioPushState May 31 22:34:53 bedroomplayer2 volumio[6036]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 22:34:53 bedroomplayer2 volumio[6036]: TypeError: Cannot read properties of null (reading 'pushState') May 31 22:34:53 bedroomplayer2 volumio[6036]: at /volumio/app/index.js:326:32 May 31 22:34:53 bedroomplayer2 volumio[6036]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) May 31 22:34:53 bedroomplayer2 volumio[6036]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) May 31 22:34:53 bedroomplayer2 volumio[6036]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) May 31 22:34:53 bedroomplayer2 volumio[6036]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:530:22) May 31 22:34:53 bedroomplayer2 volumio[6036]: at listOnTimeout (node:internal/timers:573:17) May 31 22:34:53 bedroomplayer2 volumio[6036]: at process.processTimers (node:internal/timers:514:7) May 31 22:34:53 bedroomplayer2 volumio[6036]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 22:34:53 bedroomplayer2 sudo[10581]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-31 22:33' May 31 22:34:53 bedroomplayer2 sudo[10581]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"