Dec 28 12:55:06 volumtoppen volumio-remote-updater[762]: [2025-12-28 12:55:06] [connect] Successful connection Dec 28 12:55:21 volumtoppen volumio-remote-updater[762]: [2025-12-28 12:55:21] [connect] Successful connection Dec 28 12:55:21 volumtoppen systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 28 12:55:23 volumtoppen systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 28 12:55:23 volumtoppen systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 28 12:55:32 volumtoppen volumio[1227]: info: Checking if install.sh is present Dec 28 12:55:32 volumtoppen volumio[1227]: info: Executing install.sh Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 28 12:55:33 volumtoppen sudo[7971]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Dec 28 12:55:33 volumtoppen sudo[7971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 28 12:55:33 volumtoppen volumio[1227]: info: Installing geo-tz node dependency Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 28 12:55:33 volumtoppen su[7975]: (to volumio) root on none Dec 28 12:55:33 volumtoppen su[7975]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 28 12:55:33 volumtoppen systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 28 12:55:33 volumtoppen systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 28 12:55:33 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:33 volumtoppen systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 28 12:55:33 volumtoppen systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 28 12:55:33 volumtoppen (systemd)[7977]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 28 12:55:33 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 192.168.18.101 from 192.168.18.114 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 28 12:55:33 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 12:55:34 volumtoppen volumio[1227]: info: Received Get System Info Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 12:55:34 volumtoppen volumio[1227]: info: Discovery: Getting this device information Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:34 volumtoppen volumio[1227]: info: Listing playlists Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetQueue Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreStateMachine::getQueue Dec 28 12:55:34 volumtoppen volumio[1227]: info: CorePlayQueue::getQueue Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 28 12:55:34 volumtoppen systemd[7977]: Queued start job for default target default.target. Dec 28 12:55:34 volumtoppen systemd[7977]: Created slice app.slice - User Application Slice. Dec 28 12:55:34 volumtoppen systemd[7977]: Reached target paths.target - Paths. Dec 28 12:55:34 volumtoppen systemd[7977]: Reached target timers.target - Timers. Dec 28 12:55:34 volumtoppen systemd[7977]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 28 12:55:34 volumtoppen systemd[7977]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 28 12:55:34 volumtoppen systemd[7977]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 28 12:55:34 volumtoppen systemd[7977]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 28 12:55:34 volumtoppen systemd[7977]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 28 12:55:34 volumtoppen systemd[7977]: Reached target sockets.target - Sockets. Dec 28 12:55:34 volumtoppen systemd[7977]: Reached target basic.target - Basic System. Dec 28 12:55:34 volumtoppen systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 28 12:55:34 volumtoppen systemd[1]: Started session-c1.scope - Session c1 of User volumio. Dec 28 12:55:34 volumtoppen systemd[7977]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 28 12:55:34 volumtoppen systemd[7977]: Reached target default.target - Main User Target. Dec 28 12:55:34 volumtoppen systemd[7977]: Startup finished in 755ms. Dec 28 12:55:34 volumtoppen mpris-proxy[7994]: Can't get on session bus Dec 28 12:55:34 volumtoppen systemd[7977]: mpris-proxy.service: Main process exited, code=exited, status=1/FAILURE Dec 28 12:55:34 volumtoppen systemd[7977]: mpris-proxy.service: Failed with result 'exit-code'. Dec 28 12:55:34 volumtoppen volumio[1227]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 28 12:55:34 volumtoppen volumio[1227]: info: CoreCommandRouter::volumioGetState Dec 28 12:55:36 volumtoppen volumio-remote-updater[762]: [2025-12-28 12:55:36] [connect] Successful connection Dec 28 12:55:36 volumtoppen volumio-remote-updater[762]: [2025-12-28 12:55:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766922936 101 Dec 28 12:55:36 volumtoppen volumio[1227]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 11 Dec 28 12:55:45 volumtoppen volumio[1227]: info: Enabling plugin now_playing Dec 28 12:55:45 volumtoppen volumio[1227]: info: Loading plugin "now_playing"... Dec 28 12:55:46 volumtoppen volumio[1227]: error: !!!! WARNING !!!! Dec 28 12:55:46 volumtoppen volumio[1227]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Dec 28 12:55:46 volumtoppen volumio[1227]: Require stack: Dec 28 12:55:46 volumtoppen volumio[1227]: - /data/plugins/user_interface/now_playing/dist/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/app/pluginmanager.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/app/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: error: Stack trace: Error: Cannot find module 'geo-tz' Dec 28 12:55:46 volumtoppen volumio[1227]: Require stack: Dec 28 12:55:46 volumtoppen volumio[1227]: - /data/plugins/user_interface/now_playing/dist/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/app/pluginmanager.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/app/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: - /volumio/index.js Dec 28 12:55:46 volumtoppen volumio[1227]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module._load (node:internal/modules/cjs/loader:901:27) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 28 12:55:46 volumtoppen volumio[1227]: at require (node:internal/modules/helpers:130:18) Dec 28 12:55:46 volumtoppen volumio[1227]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:46:34) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 28 12:55:46 volumtoppen volumio[1227]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 28 12:55:46 volumtoppen volumio[1227]: at require (node:internal/modules/helpers:130:18) Dec 28 12:55:46 volumtoppen volumio[1227]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Dec 28 12:55:46 volumtoppen volumio[1227]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 28 12:55:46 volumtoppen volumio[1227]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 12:55:46 volumtoppen volumio[1227]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 28 12:55:46 volumtoppen volumio[1227]: error: !!!! WARNING !!!! Dec 28 12:55:46 volumtoppen volumio[1227]: info: Done. Dec 28 12:55:51 volumtoppen volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 12:55:54 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 12:55:54 volumtoppen volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 28 12:56:23 volumtoppen volumio[1227]: info: Dec 28 12:56:23 volumtoppen volumio[1227]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 12:56:23 volumtoppen volumio[1227]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Dec 28 12:56:23 volumtoppen volumio[1227]: at /volumio/app/index.js:1534:32 Dec 28 12:56:23 volumtoppen volumio[1227]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 28 12:56:23 volumtoppen volumio[1227]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 28 12:56:23 volumtoppen volumio[1227]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Dec 28 12:56:23 volumtoppen volumio[1227]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Dec 28 12:56:23 volumtoppen volumio[1227]: at Tail. (/volumio/app/pluginmanager.js:860:24) Dec 28 12:56:23 volumtoppen volumio[1227]: at Tail.emit (node:events:514:28) Dec 28 12:56:23 volumtoppen volumio[1227]: at ReadStream. (/volumio/node_modules/tail/lib/tail.js:51:33) Dec 28 12:56:23 volumtoppen volumio[1227]: at ReadStream.emit (node:events:514:28) Dec 28 12:56:23 volumtoppen volumio[1227]: at addChunk (node:internal/streams/readable:343:12) Dec 28 12:56:23 volumtoppen volumio[1227]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 28 12:56:23 volumtoppen volumio[1227]: at Readable.push (node:internal/streams/readable:253:10) Dec 28 12:56:23 volumtoppen volumio[1227]: at node:internal/fs/streams:275:14 Dec 28 12:56:23 volumtoppen volumio[1227]: at FSReqCallback.wrapper [as oncomplete] (node:fs:701:5) Dec 28 12:56:23 volumtoppen volumio[1227]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 12:56:23 volumtoppen su[7975]: pam_unix(su:session): session closed for user volumio Dec 28 12:56:23 volumtoppen systemd[1]: session-c1.scope: Deactivated successfully. Dec 28 12:56:23 volumtoppen systemd[1]: session-c1.scope: Consumed 46.557s CPU time. Dec 28 12:56:26 volumtoppen sudo[7971]: pam_unix(sudo:session): session closed for user root Dec 28 12:56:38 volumtoppen systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Dec 28 12:56:39 volumtoppen systemd[7977]: Activating special unit exit.target... Dec 28 12:56:39 volumtoppen systemd[7977]: Stopped target default.target - Main User Target. Dec 28 12:56:39 volumtoppen systemd[7977]: Stopped target basic.target - Basic System. Dec 28 12:56:39 volumtoppen systemd[7977]: Stopped target paths.target - Paths. Dec 28 12:56:39 volumtoppen systemd[7977]: Stopped target sockets.target - Sockets. Dec 28 12:56:39 volumtoppen systemd[7977]: Stopped target timers.target - Timers. Dec 28 12:56:39 volumtoppen systemd[7977]: Closed dirmngr.socket - GnuPG network certificate management daemon. Dec 28 12:56:39 volumtoppen systemd[7977]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 28 12:56:39 volumtoppen systemd[7977]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 28 12:56:39 volumtoppen systemd[7977]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 28 12:56:39 volumtoppen systemd[7977]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 28 12:56:39 volumtoppen systemd[7977]: Removed slice app.slice - User Application Slice. Dec 28 12:56:39 volumtoppen systemd[7977]: Reached target shutdown.target - Shutdown. Dec 28 12:56:39 volumtoppen systemd[7977]: Finished systemd-exit.service - Exit the Session. Dec 28 12:56:39 volumtoppen systemd[7977]: Reached target exit.target - Exit the Session. Dec 28 12:56:39 volumtoppen systemd[1]: user@1000.service: Deactivated successfully. Dec 28 12:56:39 volumtoppen systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Dec 28 12:56:40 volumtoppen systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 28 12:56:43 volumtoppen systemd[1]: run-user-1000.mount: Deactivated successfully. Dec 28 12:56:43 volumtoppen systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Dec 28 12:56:43 volumtoppen systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 28 12:56:43 volumtoppen systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Dec 28 12:56:43 volumtoppen systemd[1]: user-1000.slice: Consumed 47.403s CPU time. Dec 28 12:56:58 volumtoppen sudo[8207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-28 12:55' Dec 28 12:56:58 volumtoppen sudo[8207]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"