Feb 23 22:29:00 volumio volumio[1170]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 23 22:29:00 volumio volumio[1170]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 23 22:29:00 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:00.372+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:00 volumio volumio[1170]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 23 22:29:00 volumio volumio[1170]: info: Folder /tmp/plugins removed Feb 23 22:29:00 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:00.400+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:02.172+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio[1170]: info: Check plugin dependencies Feb 23 22:29:02 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 22:29:02 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:02.285+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio[1170]: info: Checking if plugin already exists Feb 23 22:29:02 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:02.294+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio[1170]: info: Rename folder Feb 23 22:29:02 volumio volumio[1170]: info: Folder /tmp/downloaded_plugin.zip removed Feb 23 22:29:02 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:02.324+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio[1170]: info: Move to category Feb 23 22:29:02 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:02.984+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:02 volumio volumio[1170]: info: Checking if install.sh is present Feb 23 22:29:02 volumio volumio[1170]: info: Executing install.sh Feb 23 22:29:03 volumio sudo[6047]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Feb 23 22:29:03 volumio sudo[6047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:29:03 volumio volumio[1170]: info: Installing geo-tz node dependency Feb 23 22:29:03 volumio volumio5-onboarding[1646]: time=2026-02-23T22:29:03.056+08:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 23 22:29:42 volumio volumio[1170]: info: CoreCommandRouter::volumioGetQueue Feb 23 22:29:42 volumio volumio[1170]: info: CoreStateMachine::getQueue Feb 23 22:29:42 volumio volumio[1170]: info: CorePlayQueue::getQueue Feb 23 22:29:44 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 23 22:29:44 volumio volumio[1170]: info: Preload queue cleared Feb 23 22:29:45 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 23 22:29:45 volumio volumio[1170]: info: Preload queue cleared Feb 23 22:29:47 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 23 22:29:47 volumio volumio[1170]: info: Preload queue cleared Feb 23 22:29:48 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 23 22:29:48 volumio volumio[1170]: info: Preload queue cleared Feb 23 22:30:39 volumio sudo[6321]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 22:30:39 volumio sudo[6321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:30:39 volumio sudo[6321]: pam_unix(sudo:session): session closed for user root Feb 23 22:30:39 volumio sudo[6323]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 22:30:39 volumio sudo[6323]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 23 22:30:39 volumio sudo[6323]: pam_unix(sudo:session): session closed for user root Feb 23 22:30:39 volumio volumio[1170]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.179 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::volumioGetVisibleSources Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Feb 23 22:30:39 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 22:30:39 volumio volumio[1170]: info: Received Get System Info Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:30:39 volumio volumio[1170]: info: Discovery: Getting this device information Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Feb 23 22:30:39 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Feb 23 22:30:39 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Feb 23 22:30:39 volumio volumio[1170]: info: Listing playlists Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 23 22:30:39 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 23 22:30:40 volumio volumio[1170]: info: CoreCommandRouter::Close All Modals sent Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 22:30:41 volumio volumio[1170]: info: Received Get System Info Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 23 22:30:41 volumio volumio[1170]: info: Discovery: Getting this device information Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::volumioGetState Feb 23 22:30:41 volumio volumio[1170]: info: CorePlayQueue::getTrack 0 Feb 23 22:30:41 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 23 22:30:45 volumio volumio[1170]: info: Enabling plugin now_playing Feb 23 22:30:45 volumio volumio[1170]: info: Loading plugin "now_playing"... Feb 23 22:30:45 volumio volumio[1170]: error: !!!! WARNING !!!! Feb 23 22:30:45 volumio volumio[1170]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Feb 23 22:30:45 volumio volumio[1170]: Require stack: Feb 23 22:30:45 volumio volumio[1170]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/app/pluginmanager.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/app/index.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/index.js Feb 23 22:30:45 volumio volumio[1170]: error: Stack trace: Error: Cannot find module 'geo-tz' Feb 23 22:30:45 volumio volumio[1170]: Require stack: Feb 23 22:30:45 volumio volumio[1170]: - /data/plugins/user_interface/now_playing/dist/index.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/app/pluginmanager.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/app/index.js Feb 23 22:30:45 volumio volumio[1170]: - /volumio/index.js Feb 23 22:30:45 volumio volumio[1170]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Feb 23 22:30:45 volumio volumio[1170]: at Module._load (node:internal/modules/cjs/loader:901:27) Feb 23 22:30:45 volumio volumio[1170]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 23 22:30:45 volumio volumio[1170]: at require (node:internal/modules/helpers:130:18) Feb 23 22:30:45 volumio volumio[1170]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Feb 23 22:30:45 volumio volumio[1170]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Feb 23 22:30:45 volumio volumio[1170]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Feb 23 22:30:45 volumio volumio[1170]: at Module.load (node:internal/modules/cjs/loader:1091:32) Feb 23 22:30:45 volumio volumio[1170]: at Module._load (node:internal/modules/cjs/loader:938:12) Feb 23 22:30:45 volumio volumio[1170]: at Module.require (node:internal/modules/cjs/loader:1115:19) Feb 23 22:30:45 volumio volumio[1170]: at require (node:internal/modules/helpers:130:18) Feb 23 22:30:45 volumio volumio[1170]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Feb 23 22:30:45 volumio volumio[1170]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Feb 23 22:30:45 volumio volumio[1170]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 23 22:30:45 volumio volumio[1170]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 23 22:30:45 volumio volumio[1170]: error: !!!! WARNING !!!! Feb 23 22:30:45 volumio volumio[1170]: info: Done. Feb 23 22:30:49 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 23 22:30:49 volumio volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 22:30:51 volumio volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 23 22:30:52 volumio volumio[1170]: info: CoreCommandRouter::Close All Modals sent Feb 23 22:30:52 volumio volumio[1170]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 22:30:52 volumio volumio[1170]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Feb 23 22:30:52 volumio volumio[1170]: at /volumio/app/index.js:1534:32 Feb 23 22:30:52 volumio volumio[1170]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 23 22:30:52 volumio volumio[1170]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 23 22:30:52 volumio volumio[1170]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Feb 23 22:30:52 volumio volumio[1170]: at CoreCommandRouter.closeModals (/volumio/app/index.js:1954:15) Feb 23 22:30:52 volumio volumio[1170]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1975:26) Feb 23 22:30:52 volumio volumio[1170]: at Socket.emit (node:events:514:28) Feb 23 22:30:52 volumio volumio[1170]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 23 22:30:52 volumio volumio[1170]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 23 22:30:52 volumio volumio[1170]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 22:30:53 volumio sudo[6369]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-23 22:29' Feb 23 22:30:53 volumio sudo[6369]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"