Dec 21 10:38:01 volumio sudo[2193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 21 10:38:01 volumio sudo[2193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 10:38:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 21 10:38:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 21 10:38:01 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 21 10:38:01 volumio sudo[2193]: pam_unix(sudo:session): session closed for user root Dec 21 10:38:01 volumio volumio[1248]: info: Remote SSH Started Dec 21 10:38:01 volumio autossh[2196]: port set to 0, monitoring disabled Dec 21 10:38:01 volumio autossh[2196]: starting ssh (count 1) Dec 21 10:38:01 volumio autossh[2196]: ssh child pid is 2199 Dec 21 10:38:01 volumio volumio[1248]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 21 10:38:01 volumio volumio[1248]: info: CoreCommandRouter::volumioGetState Dec 21 10:38:01 volumio volumio[1248]: info: CorePlayQueue::getTrack 0 Dec 21 10:38:02 volumio volumiossh-tunnel[2199]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 21 10:38:03 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 10:38:03 volumio volumio[1248]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 21 10:38:03 volumio volumio[1248]: info: Received Get System Version Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 10:38:03 volumio volumio[1248]: info: Received Get System Info Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 21 10:38:03 volumio volumio[1248]: info: Discovery: Getting this device information Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::volumioGetState Dec 21 10:38:03 volumio volumio[1248]: info: CorePlayQueue::getTrack 0 Dec 21 10:38:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 21 10:38:04 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 21 10:38:04 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 21 10:38:05 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 21 10:38:05 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 21 10:38:08 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 21 10:38:08 volumio volumio[1248]: 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 21 10:38:08 volumio volumio[1248]: info: CoreCommandRouter::volumioGetState Dec 21 10:38:08 volumio volumio[1248]: info: CorePlayQueue::getTrack 0 Dec 21 10:38:12 volumio kernel: usb 2-1.2: new full-speed USB device number 4 using ehci-pci Dec 21 10:38:12 volumio kernel: usb 2-1.2: New USB device found, idVendor=04d9, idProduct=fc4d, bcdDevice= 1.52 Dec 21 10:38:12 volumio kernel: usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Dec 21 10:38:12 volumio kernel: usb 2-1.2: Product: USB Gaming Mouse Dec 21 10:38:12 volumio kernel: input: USB Gaming Mouse as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1.2/2-1.2:1.0/0003:04D9:FC4D.0004/input/input13 Dec 21 10:38:12 volumio kernel: hid-generic 0003:04D9:FC4D.0004: input,hidraw0: USB HID v1.10 Mouse [USB Gaming Mouse] on usb-0000:00:1d.7-1.2/input0 Dec 21 10:38:12 volumio kernel: input: USB Gaming Mouse as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1.2/2-1.2:1.1/0003:04D9:FC4D.0005/input/input14 Dec 21 10:38:12 volumio kernel: hid-generic 0003:04D9:FC4D.0005: input,hidraw2: USB HID v1.10 Keyboard [USB Gaming Mouse] on usb-0000:00:1d.7-1.2/input1 Dec 21 10:38:12 volumio kernel: input: USB Gaming Mouse Consumer Control as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1.2/2-1.2:1.2/0003:04D9:FC4D.0006/input/input15 Dec 21 10:38:12 volumio kernel: input: USB Gaming Mouse as /devices/pci0000:00/0000:00:1d.7/usb2/2-1/2-1.2/2-1.2:1.2/0003:04D9:FC4D.0006/input/input16 Dec 21 10:38:12 volumio kernel: hid-generic 0003:04D9:FC4D.0006: input,hiddev1,hidraw3: USB HID v1.10 Device [USB Gaming Mouse] on usb-0000:00:1d.7-1.2/input2 Dec 21 10:38:12 volumio systemd-logind[905]: Watching system buttons on /dev/input/event6 (USB Gaming Mouse) Dec 21 10:38:12 volumio systemd-logind[905]: Watching system buttons on /dev/input/event13 (USB Gaming Mouse Consumer Control) Dec 21 10:38:12 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 10:38:12 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:38:12 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 21 10:38:13 volumio volumio-remote-updater[908]: Test mode disabled Dec 21 10:38:13 volumio volumio-remote-updater[908]: Alpha mode disabled Dec 21 10:38:13 volumio volumio-remote-updater[908]: Alpha legacy test mode disabled Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::volumioGetBrowseSources Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 10:38:13 volumio volumio[1248]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 21 10:38:13 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 21 10:38:17 volumio volumio[1248]: info: Received Get System Version Dec 21 10:38:17 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 10:38:17 volumio go-librespot[1951]: time="2025-12-21T10:38:17Z" level=trace msg="sent dealer ping" Dec 21 10:38:18 volumio go-librespot[1951]: time="2025-12-21T10:38:18Z" level=trace msg="received dealer pong" Dec 21 10:38:26 volumio volumio[1248]: error: MyVolumio Plugins failed to start in a timely fashion Dec 21 10:38:26 volumio volumio[1248]: [Metrics] CommandRouter: 47s 722.98ms Dec 21 10:38:26 volumio volumio[1248]: info: CoreCommandRouter::volumiosetStartupVolume Dec 21 10:38:26 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 21 10:38:26 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 21 10:38:26 volumio volumio[1248]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:38:26 volumio volumio[1248]: info: CoreCommandRouter::Close All Modals sent Dec 21 10:38:27 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 21 10:38:27 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 21 10:38:27 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 21 10:38:33 volumio volumio[1248]: info: BOOT COMPLETED Dec 21 10:38:45 volumio volumio[1248]: info: CoreCommandRouter::volumioGetState Dec 21 10:38:45 volumio volumio[1248]: info: CorePlayQueue::getTrack 0 Dec 21 10:38:47 volumio systemd[1]: Starting apt-daily-upgrade.service - Daily apt upgrade and clean activities... Dec 21 10:38:47 volumio systemd[1]: apt-daily-upgrade.service: Deactivated successfully. Dec 21 10:38:47 volumio systemd[1]: Finished apt-daily-upgrade.service - Daily apt upgrade and clean activities. Dec 21 10:38:47 volumio go-librespot[1951]: time="2025-12-21T10:38:47Z" level=trace msg="sent dealer ping" Dec 21 10:38:48 volumio go-librespot[1951]: time="2025-12-21T10:38:48Z" level=trace msg="received dealer pong" Dec 21 10:38:49 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 10:38:49 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 10:39:01 volumio volumio[1248]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Dec 21 10:39:03 volumio volumio[1248]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Dec 21 10:39:03 volumio volumio[1248]: info: Folder /tmp/plugins removed Dec 21 10:39:03 volumio volumio[1248]: info: Check plugin dependencies Dec 21 10:39:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 21 10:39:03 volumio volumio[1248]: info: Checking if plugin already exists Dec 21 10:39:03 volumio volumio[1248]: info: Rename folder Dec 21 10:39:03 volumio volumio[1248]: info: Folder /tmp/downloaded_plugin.zip removed Dec 21 10:39:03 volumio volumio[1248]: info: Move to category Dec 21 10:39:03 volumio volumio[1248]: info: Checking if install.sh is present Dec 21 10:39:03 volumio volumio[1248]: info: Executing install.sh Dec 21 10:39:03 volumio sudo[2552]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Dec 21 10:39:03 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 21 10:39:03 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Dec 21 10:39:03 volumio volumio[1248]: info: Install script completed Dec 21 10:39:03 volumio volumio[1248]: info: Adding reference to registry Dec 21 10:39:03 volumio volumio[1248]: info: Done installing plugin. Dec 21 10:39:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 21 10:39:03 volumio volumio[1248]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 21 10:39:03 volumio volumio[1248]: info: Installing ferrum streaming control technology Dependencies Dec 21 10:39:03 volumio volumio[1248]: Plugin install end detected on script Dec 21 10:39:03 volumio volumio[1248]: info: Folder /tmp/plugins removed Dec 21 10:39:03 volumio volumio[1248]: info: Folder /tmp/downloaded_plugin.zip removed Dec 21 10:39:03 volumio volumio[1248]: info: Folder /data/temp removed Dec 21 10:39:05 volumio volumio[1248]: info: Enabling plugin ferrum_streaming_control_technology Dec 21 10:39:05 volumio volumio[1248]: info: Loading plugin "ferrum_streaming_control_technology"... Dec 21 10:39:07 volumio volumio[1248]: error: !!!! WARNING !!!! Dec 21 10:39:07 volumio volumio[1248]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Dec 21 10:39:07 volumio volumio[1248]: Require stack: Dec 21 10:39:07 volumio volumio[1248]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Dec 21 10:39:07 volumio volumio[1248]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/app/pluginmanager.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/app/index.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/index.js Dec 21 10:39:07 volumio volumio[1248]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Dec 21 10:39:07 volumio volumio[1248]: Require stack: Dec 21 10:39:07 volumio volumio[1248]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Dec 21 10:39:07 volumio volumio[1248]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/app/pluginmanager.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/app/index.js Dec 21 10:39:07 volumio volumio[1248]: - /volumio/index.js Dec 21 10:39:07 volumio volumio[1248]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Dec 21 10:39:07 volumio volumio[1248]: at Module._load (node:internal/modules/cjs/loader:901:27) Dec 21 10:39:07 volumio volumio[1248]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 21 10:39:07 volumio volumio[1248]: at require (node:internal/modules/helpers:130:18) Dec 21 10:39:07 volumio volumio[1248]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Dec 21 10:39:07 volumio volumio[1248]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 21 10:39:07 volumio volumio[1248]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 21 10:39:07 volumio volumio[1248]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 21 10:39:07 volumio volumio[1248]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 21 10:39:07 volumio volumio[1248]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 21 10:39:07 volumio volumio[1248]: at require (node:internal/modules/helpers:130:18) Dec 21 10:39:07 volumio volumio[1248]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Dec 21 10:39:07 volumio volumio[1248]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 21 10:39:07 volumio volumio[1248]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 21 10:39:07 volumio volumio[1248]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 21 10:39:07 volumio volumio[1248]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 21 10:39:07 volumio volumio[1248]: error: !!!! WARNING !!!! Dec 21 10:39:07 volumio volumio[1248]: info: Done. Dec 21 10:39:16 volumio volumio[1248]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/Bluetoothremote/volumio/bookworm/amd64 Dec 21 10:39:16 volumio volumio[1248]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:39:16 volumio volumio[1248]: TypeError: Cannot read properties of null (reading 'broadcastMessage') Dec 21 10:39:16 volumio volumio[1248]: at /volumio/app/index.js:1534:32 Dec 21 10:39:16 volumio volumio[1248]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 21 10:39:16 volumio volumio[1248]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 21 10:39:16 volumio volumio[1248]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Dec 21 10:39:16 volumio volumio[1248]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Dec 21 10:39:16 volumio volumio[1248]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Dec 21 10:39:16 volumio volumio[1248]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) Dec 21 10:39:16 volumio volumio[1248]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1220:47) Dec 21 10:39:16 volumio volumio[1248]: at Socket.emit (node:events:514:28) Dec 21 10:39:16 volumio volumio[1248]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 21 10:39:16 volumio volumio[1248]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 21 10:39:17 volumio volumio[1248]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:39:17 volumio sudo[2600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 10:38' Dec 21 10:39:17 volumio sudo[2600]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"