Oct 11 15:24:27 volumio volumio[3673]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Oct 11 15:24:27 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:27.523Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/bookworm/amd64 Oct 11 15:24:29 volumio volumio[3673]: info: Folder /tmp/plugins removed Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.398Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.432Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: Check plugin dependencies Oct 11 15:24:29 volumio volumio[3673]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.442Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: Checking if plugin already exists Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.442Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: Rename folder Oct 11 15:24:29 volumio volumio[3673]: info: Folder /tmp/downloaded_plugin.zip removed Oct 11 15:24:29 volumio volumio[3673]: info: Move to category Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.450Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.836Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: Checking if install.sh is present Oct 11 15:24:29 volumio volumio[3673]: info: Executing install.sh Oct 11 15:24:29 volumio sudo[24363]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Oct 11 15:24:29 volumio sudo[24363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 11 15:24:29 volumio sudo[24363]: pam_unix(sudo:session): session closed for user root Oct 11 15:24:29 volumio volumio[3673]: info: Installing ferrum streaming control technology Dependencies Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.852Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: Plugin install end detected on script Oct 11 15:24:29 volumio volumio[3673]: info: Install script completed Oct 11 15:24:29 volumio volumio[3673]: info: Adding reference to registry Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.853Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio5-onboarding[2085]: time=2026-10-11T12:24:29.854Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:24:29 volumio volumio[3673]: info: Done installing plugin. Oct 11 15:24:29 volumio volumio[3673]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 11 15:24:29 volumio volumio[3673]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 11 15:24:29 volumio volumio[3673]: info: Folder /tmp/plugins removed Oct 11 15:24:29 volumio volumio[3673]: info: Folder /tmp/downloaded_plugin.zip removed Oct 11 15:24:29 volumio volumio[3673]: info: Folder /data/temp removed Oct 11 15:24:38 volumio volumio[3673]: info: Enabling plugin ferrum_streaming_control_technology Oct 11 15:24:38 volumio volumio[3673]: info: Loading plugin "ferrum_streaming_control_technology"... Oct 11 15:24:39 volumio volumio[3673]: error: !!!! WARNING !!!! Oct 11 15:24:39 volumio volumio[3673]: 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' Oct 11 15:24:39 volumio volumio[3673]: Require stack: Oct 11 15:24:39 volumio volumio[3673]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Oct 11 15:24:39 volumio volumio[3673]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/app/pluginmanager.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/app/index.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/index.js Oct 11 15:24:39 volumio volumio[3673]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Oct 11 15:24:39 volumio volumio[3673]: Require stack: Oct 11 15:24:39 volumio volumio[3673]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Oct 11 15:24:39 volumio volumio[3673]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/app/pluginmanager.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/app/index.js Oct 11 15:24:39 volumio volumio[3673]: - /volumio/index.js Oct 11 15:24:39 volumio volumio[3673]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Oct 11 15:24:39 volumio volumio[3673]: at Module._load (node:internal/modules/cjs/loader:901:27) Oct 11 15:24:39 volumio volumio[3673]: at Module.require (node:internal/modules/cjs/loader:1115:19) Oct 11 15:24:39 volumio volumio[3673]: at require (node:internal/modules/helpers:130:18) Oct 11 15:24:39 volumio volumio[3673]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Oct 11 15:24:39 volumio volumio[3673]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Oct 11 15:24:39 volumio volumio[3673]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Oct 11 15:24:39 volumio volumio[3673]: at Module.load (node:internal/modules/cjs/loader:1091:32) Oct 11 15:24:39 volumio volumio[3673]: at Module._load (node:internal/modules/cjs/loader:938:12) Oct 11 15:24:39 volumio volumio[3673]: at Module.require (node:internal/modules/cjs/loader:1115:19) Oct 11 15:24:39 volumio volumio[3673]: at require (node:internal/modules/helpers:130:18) Oct 11 15:24:39 volumio volumio[3673]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Oct 11 15:24:39 volumio volumio[3673]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Oct 11 15:24:39 volumio volumio[3673]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Oct 11 15:24:39 volumio volumio[3673]: at Module.load (node:internal/modules/cjs/loader:1091:32) Oct 11 15:24:39 volumio volumio[3673]: at Module._load (node:internal/modules/cjs/loader:938:12) Oct 11 15:24:39 volumio volumio[3673]: error: !!!! WARNING !!!! Oct 11 15:24:39 volumio volumio[3673]: info: Done. Oct 11 15:25:12 volumio volumio[3673]: info: Starting Uninstall of plugin user_interface - ferrum_streaming_control_technology Oct 11 15:25:12 volumio volumio[3673]: info: Uninstalling plugin ferrum_streaming_control_technology Oct 11 15:25:12 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:12.133Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:25:12 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:12.135Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Oct 11 15:25:50 volumio volumio[3673]: info: CorePlayQueue::getTrack 1 Oct 11 15:25:50 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:50 volumio volumio[3673]: info: Prefetching next song Oct 11 15:25:50 volumio volumio[3673]: info: DOING PREFETCH IN MPD Oct 11 15:25:50 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand add "USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" Oct 11 15:25:50 volumio volumio[3673]: info: Oct 11 15:25:50 volumio volumio[3673]: ---------------------------- MPD announces system playlist update Oct 11 15:25:50 volumio volumio[3673]: info: Ignoring MPD Status Update Oct 11 15:25:50 volumio volumio[3673]: info: sendMpdCommand add "USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" took 1 milliseconds Oct 11 15:25:50 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 11 15:25:50 volumio volumio[3673]: info: Oct 11 15:25:50 volumio volumio[3673]: ---------------------------- MPD announces system playlist update Oct 11 15:25:50 volumio volumio[3673]: info: Ignoring MPD Status Update Oct 11 15:25:50 volumio volumio[3673]: info: ------------------------------ 1ms Oct 11 15:25:50 volumio volumio[3673]: info: sendMpdCommand consume 1 took 1 milliseconds Oct 11 15:25:50 volumio volumio[3673]: info: ------------------------------ 1ms Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::startPlaybackTimer Oct 11 15:25:54 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:54 volumio volumio[3673]: info: Oct 11 15:25:54 volumio volumio[3673]: ---------------------------- MPD announces system playlist update Oct 11 15:25:54 volumio volumio[3673]: info: Ignoring MPD Status Update Oct 11 15:25:54 volumio volumio[3673]: info: Oct 11 15:25:54 volumio volumio[3673]: ---------------------------- MPD announces state update: player Oct 11 15:25:54 volumio volumio[3673]: info: ControllerMpd::getState Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:25:54 volumio volumio[3673]: info: Oct 11 15:25:54 volumio volumio[3673]: ---------------------------- MPD announces system playlist update Oct 11 15:25:54 volumio volumio[3673]: info: Ignoring MPD Status Update Oct 11 15:25:54 volumio volumio[3673]: info: Oct 11 15:25:54 volumio volumio[3673]: ---------------------------- MPD announces state update: player Oct 11 15:25:54 volumio volumio[3673]: info: ControllerMpd::getState Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand status Oct 11 15:25:54 volumio volumio[3673]: info: ------------------------------ 1ms Oct 11 15:25:54 volumio volumio[3673]: info: sendMpdCommand status took 0 milliseconds Oct 11 15:25:54 volumio volumio[3673]: info: ------------------------------ 0ms Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::parseState Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:25:54 volumio volumio[3673]: info: sendMpdCommand status took 0 milliseconds Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::parseState Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 15:25:54 volumio volumio[3673]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:25:54 volumio volumio[3673]: info: ControllerMpd::pushState Oct 11 15:25:54 volumio volumio[3673]: info: CoreCommandRouter::servicePushState Oct 11 15:25:54 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:54 volumio volumio[3673]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":329,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1639 Kbps","isStreaming":false,"title":"And You My Love","artist":"Chris Rea","album":"Auberge","uri":"USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac","trackType":"flac"} Oct 11 15:25:54 volumio volumio[3673]: verbose: CURRENT POSITION 2 Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::syncState stateService play Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:25:54 volumio volumio[3673]: info: Received an update from plugin. extracting info from payload Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::pushState Oct 11 15:25:54 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:54 volumio volumio[3673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:25:54 volumio volumio[3673]: info: CoreCommandRouter::volumioPushState Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.961Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:61040 @ 0xc00040a030" state=STATUS_PLAYING positionMs=2 volume=100 Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.961Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.0.195:61040 @ 0xc00057ee70" state=STATUS_PLAYING positionMs=2 volume=100 Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.961Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:61040 @ 0xc00040a030" id="mnt/USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" title="And You My Love" Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.962Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.0.195:61040 @ 0xc00057ee70" id="mnt/USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" title="And You My Love" Oct 11 15:25:54 volumio volumio[3673]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Oct 11 15:25:54 volumio volumio[3673]: info: ------------------------------ 3ms Oct 11 15:25:54 volumio volumio[3673]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 15:25:54 volumio volumio[3673]: verbose: ControllerMpd::parseTrackInfo Oct 11 15:25:54 volumio volumio[3673]: info: ControllerMpd::pushState Oct 11 15:25:54 volumio volumio[3673]: info: CoreCommandRouter::servicePushState Oct 11 15:25:54 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:54 volumio volumio[3673]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2,"duration":329,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1639 Kbps","isStreaming":false,"title":"And You My Love","artist":"Chris Rea","album":"Auberge","uri":"USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac","trackType":"flac"} Oct 11 15:25:54 volumio volumio[3673]: verbose: CURRENT POSITION 2 Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::syncState stateService play Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::syncState currentStatus play Oct 11 15:25:54 volumio volumio[3673]: info: Received an update from plugin. extracting info from payload Oct 11 15:25:54 volumio volumio[3673]: info: CoreStateMachine::pushState Oct 11 15:25:54 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:54 volumio volumio[3673]: info: CoreCommandRouter::volumioPushState Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.964Z level=INFO msg="emitting player state changed event" component=server peer="192.168.0.195:61040 @ 0xc00040a030" state=STATUS_PLAYING positionMs=2 volume=100 Oct 11 15:25:54 volumio volumio[3673]: error: ControllerMpd::pushError: TypeError: Cannot read properties of null (reading 'pushState') Oct 11 15:25:54 volumio volumio[3673]: info: ------------------------------ 5ms Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.964Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%03,192.168.0.195:61040 @ 0xc00057ee70" state=STATUS_PLAYING positionMs=2 volume=100 Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.965Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.195:61040 @ 0xc00040a030" id="mnt/USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" title="And You My Love" Oct 11 15:25:54 volumio volumio5-onboarding[2085]: time=2026-10-11T12:25:54.965Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%03,192.168.0.195:61040 @ 0xc00057ee70" id="mnt/USB/sys/збірка/Мои любимые треки июня 2026/11 And You My Love.flac" title="And You My Love" Oct 11 15:25:55 volumio volumio[3673]: info: CoreStateMachine::pushState Oct 11 15:25:55 volumio volumio[3673]: info: CorePlayQueue::getTrack 2 Oct 11 15:25:55 volumio volumio[3673]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 15:25:55 volumio volumio[3673]: info: CoreCommandRouter::volumioPushState Oct 11 15:25:55 volumio volumio[3673]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:25:55 volumio volumio[3673]: TypeError: Cannot read properties of null (reading 'pushState') Oct 11 15:25:55 volumio volumio[3673]: at /volumio/app/index.js:326:32 Oct 11 15:25:55 volumio volumio[3673]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 11 15:25:55 volumio volumio[3673]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 11 15:25:55 volumio volumio[3673]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Oct 11 15:25:55 volumio volumio[3673]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Oct 11 15:25:55 volumio volumio[3673]: at listOnTimeout (node:internal/timers:573:17) Oct 11 15:25:55 volumio volumio[3673]: at process.processTimers (node:internal/timers:514:7) Oct 11 15:25:55 volumio volumio[3673]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 15:25:55 volumio sudo[24604]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 15:24' Oct 11 15:25:55 volumio sudo[24604]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"