-- Logs begin at Sun 2025-06-15 07:36:36 UTC, end at Sun 2025-06-15 07:50:15 UTC. -- Jun 15 07:49:01 volumio go-librespot[1637]: time="2025-06-15T07:49:01Z" level=debug msg="fetched chunk 6/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:49:15 volumio go-librespot[1637]: time="2025-06-15T07:49:15Z" level=debug msg="fetched chunk 7/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:49:15 volumio volumio[1141]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/randomizer/volumio/buster/amd64 Jun 15 07:49:17 volumio volumio[1141]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/randomizer/volumio/buster/amd64 Jun 15 07:49:17 volumio volumio[1141]: info: Folder /tmp/plugins removed Jun 15 07:49:17 volumio volumio[1141]: info: Check plugin dependencies Jun 15 07:49:17 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 07:49:17 volumio volumio[1141]: info: Checking if plugin already exists Jun 15 07:49:17 volumio volumio[1141]: info: Rename folder Jun 15 07:49:17 volumio volumio[1141]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 07:49:17 volumio volumio[1141]: info: Move to category Jun 15 07:49:17 volumio volumio[1141]: info: Checking if install.sh is present Jun 15 07:49:17 volumio volumio[1141]: info: Executing install.sh Jun 15 07:49:17 volumio sudo[4580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/randomizer/install.sh Jun 15 07:49:17 volumio sudo[4580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 07:49:17 volumio volumio[1141]: info: Installing randomizer Dependencies Jun 15 07:49:17 volumio sudo[4580]: pam_unix(sudo:session): session closed for user root Jun 15 07:49:17 volumio volumio[1141]: info: Install script completed Jun 15 07:49:17 volumio volumio[1141]: info: Adding reference to registry Jun 15 07:49:17 volumio volumio[1141]: info: Done installing plugin. Jun 15 07:49:17 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 07:49:17 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 07:49:17 volumio volumio[1141]: info: Folder /tmp/plugins removed Jun 15 07:49:17 volumio volumio[1141]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 07:49:17 volumio volumio[1141]: info: Folder /data/temp removed Jun 15 07:49:17 volumio volumio[1141]: Plugin install end detected on script Jun 15 07:49:19 volumio volumio[1141]: info: Enabling plugin randomizer Jun 15 07:49:19 volumio volumio[1141]: info: Loading plugin "randomizer"... Jun 15 07:49:19 volumio volumio[1141]: info: PLUGIN START: randomizer Jun 15 07:49:19 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 07:49:19 volumio volumio[1141]: info: [1749973759379] CoreMusicLibrary::Adding element Randomizer Jun 15 07:49:19 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 07:49:19 volumio volumio[1141]: Cannot find translation for source Radio Paradise Jun 15 07:49:19 volumio volumio[1141]: Cannot find translation for source Spotify Jun 15 07:49:19 volumio volumio[1141]: Cannot find translation for source Calm Radio Jun 15 07:49:19 volumio volumio[1141]: Cannot find translation for source Randomizer Jun 15 07:49:19 volumio volumio[1141]: info: Done. Jun 15 07:49:19 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jun 15 07:49:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 07:49:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 07:49:22 volumio volumio[1141]: info: Discovery: Getting this device information Jun 15 07:49:22 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState Jun 15 07:49:22 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 07:49:24 volumio volumio[1141]: info: Enabling plugin randomizer Jun 15 07:49:24 volumio volumio[1141]: info: Loading plugin "randomizer"... Jun 15 07:49:24 volumio volumio[1141]: info: Applying required configuration parameters for plugin randomizer Jun 15 07:49:24 volumio volumio[1141]: info: PLUGIN START: randomizer Jun 15 07:49:24 volumio volumio[1141]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 15 07:49:24 volumio volumio[1141]: info: [1749973764220] CoreMusicLibrary::Adding element Randomizer Jun 15 07:49:24 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 07:49:24 volumio volumio[1141]: Cannot find translation for source Radio Paradise Jun 15 07:49:24 volumio volumio[1141]: Cannot find translation for source Spotify Jun 15 07:49:24 volumio volumio[1141]: Cannot find translation for source Calm Radio Jun 15 07:49:24 volumio volumio[1141]: Cannot find translation for source Randomizer Jun 15 07:49:24 volumio volumio[1141]: info: Done. Jun 15 07:49:24 volumio volumio[1141]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 15 07:49:28 volumio go-librespot[1637]: time="2025-06-15T07:49:28Z" level=trace msg="sent dealer ping" Jun 15 07:49:28 volumio go-librespot[1637]: time="2025-06-15T07:49:28Z" level=trace msg="received dealer pong" Jun 15 07:49:29 volumio go-librespot[1637]: time="2025-06-15T07:49:29Z" level=debug msg="fetched chunk 8/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:49:42 volumio go-librespot[1637]: time="2025-06-15T07:49:42Z" level=debug msg="fetched chunk 9/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:49:45 volumio volumio[1141]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/buster/amd64 Jun 15 07:49:47 volumio volumio[1141]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/buster/amd64 Jun 15 07:49:47 volumio volumio[1141]: info: Folder /tmp/plugins removed Jun 15 07:49:47 volumio volumio[1141]: info: Check plugin dependencies Jun 15 07:49:47 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 15 07:49:47 volumio volumio[1141]: info: Checking if plugin already exists Jun 15 07:49:47 volumio volumio[1141]: info: Rename folder Jun 15 07:49:47 volumio volumio[1141]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 07:49:47 volumio volumio[1141]: info: Move to category Jun 15 07:49:47 volumio volumio[1141]: info: Checking if install.sh is present Jun 15 07:49:47 volumio volumio[1141]: info: Executing install.sh Jun 15 07:49:47 volumio sudo[4702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Jun 15 07:49:47 volumio sudo[4702]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 15 07:49:47 volumio sudo[4702]: pam_unix(sudo:session): session closed for user root Jun 15 07:49:47 volumio volumio[1141]: info: Install script completed Jun 15 07:49:47 volumio volumio[1141]: info: Adding reference to registry Jun 15 07:49:47 volumio volumio[1141]: info: Done installing plugin. Jun 15 07:49:47 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 07:49:47 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 07:49:47 volumio volumio[1141]: info: Installing ferrum streaming control technology Dependencies Jun 15 07:49:47 volumio volumio[1141]: Plugin install end detected on script Jun 15 07:49:47 volumio volumio[1141]: info: Folder /tmp/plugins removed Jun 15 07:49:47 volumio volumio[1141]: info: Folder /tmp/downloaded_plugin.zip removed Jun 15 07:49:47 volumio volumio[1141]: info: Folder /data/temp removed Jun 15 07:49:48 volumio volumio[1141]: info: Enabling plugin ferrum_streaming_control_technology Jun 15 07:49:48 volumio volumio[1141]: info: Loading plugin "ferrum_streaming_control_technology"... Jun 15 07:49:49 volumio volumio[1141]: error: !!!! WARNING !!!! Jun 15 07:49:49 volumio volumio[1141]: 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' Jun 15 07:49:49 volumio volumio[1141]: Require stack: Jun 15 07:49:49 volumio volumio[1141]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Jun 15 07:49:49 volumio volumio[1141]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/app/pluginmanager.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/app/index.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/index.js Jun 15 07:49:49 volumio volumio[1141]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Jun 15 07:49:49 volumio volumio[1141]: Require stack: Jun 15 07:49:49 volumio volumio[1141]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Jun 15 07:49:49 volumio volumio[1141]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/app/pluginmanager.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/app/index.js Jun 15 07:49:49 volumio volumio[1141]: - /volumio/index.js Jun 15 07:49:49 volumio volumio[1141]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Jun 15 07:49:49 volumio volumio[1141]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Jun 15 07:49:49 volumio volumio[1141]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jun 15 07:49:49 volumio volumio[1141]: at require (internal/modules/cjs/helpers.js:107:18) Jun 15 07:49:49 volumio volumio[1141]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Jun 15 07:49:49 volumio volumio[1141]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jun 15 07:49:49 volumio volumio[1141]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jun 15 07:49:49 volumio volumio[1141]: at Module.load (internal/modules/cjs/loader.js:979:32) Jun 15 07:49:49 volumio volumio[1141]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jun 15 07:49:49 volumio volumio[1141]: at Module.require (internal/modules/cjs/loader.js:1003:19) Jun 15 07:49:49 volumio volumio[1141]: at require (internal/modules/cjs/helpers.js:107:18) Jun 15 07:49:49 volumio volumio[1141]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Jun 15 07:49:49 volumio volumio[1141]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Jun 15 07:49:49 volumio volumio[1141]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Jun 15 07:49:49 volumio volumio[1141]: at Module.load (internal/modules/cjs/loader.js:979:32) Jun 15 07:49:49 volumio volumio[1141]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Jun 15 07:49:49 volumio volumio[1141]: error: !!!! WARNING !!!! Jun 15 07:49:49 volumio volumio[1141]: info: Done. Jun 15 07:49:52 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 15 07:49:52 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 15 07:49:52 volumio volumio[1141]: info: Discovery: Getting this device information Jun 15 07:49:52 volumio volumio[1141]: info: CoreCommandRouter::volumioGetState Jun 15 07:49:52 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 15 07:49:55 volumio go-librespot[1637]: time="2025-06-15T07:49:55Z" level=debug msg="fetched chunk 10/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:49:58 volumio go-librespot[1637]: time="2025-06-15T07:49:58Z" level=trace msg="sent dealer ping" Jun 15 07:49:58 volumio go-librespot[1637]: time="2025-06-15T07:49:58Z" level=trace msg="received dealer pong" Jun 15 07:50:03 volumio volumio[1141]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 15 07:50:09 volumio go-librespot[1637]: time="2025-06-15T07:50:09Z" level=debug msg="fetched chunk 11/30, size: 524288" uri="spotify:track:1JkKKmP75NOoyLQ0d48lom" Jun 15 07:50:11 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 15 07:50:11 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 15 07:50:15 volumio volumio[1141]: info: Starting Uninstall of plugin music_service - calmradio Jun 15 07:50:15 volumio volumio[1141]: info: Uninstalling plugin calmradio Jun 15 07:50:15 volumio volumio[1141]: info: CoreCommandRouter::volumioRemoveToBrowseSourcescalmradio Jun 15 07:50:15 volumio volumio[1141]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 15 07:50:15 volumio volumio[1141]: Cannot find translation for source Radio Paradise Jun 15 07:50:15 volumio volumio[1141]: Cannot find translation for source Spotify Jun 15 07:50:15 volumio volumio[1141]: Cannot find translation for source Calm Radio Jun 15 07:50:15 volumio volumio[1141]: Cannot find translation for source Randomizer Jun 15 07:50:15 volumio volumio[1141]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 07:50:15 volumio volumio[1141]: TypeError: Cannot read property 'broadcastMessage' of null Jun 15 07:50:15 volumio volumio[1141]: at /volumio/app/index.js:1534:32 Jun 15 07:50:15 volumio volumio[1141]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jun 15 07:50:15 volumio volumio[1141]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jun 15 07:50:15 volumio volumio[1141]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Jun 15 07:50:15 volumio volumio[1141]: at CoreMusicLibrary.pushBrowseSources (/volumio/app/musiclibrary.js:584:29) Jun 15 07:50:15 volumio volumio[1141]: at CoreMusicLibrary.removeBrowseSource (/volumio/app/musiclibrary.js:262:15) Jun 15 07:50:15 volumio volumio[1141]: at CoreCommandRouter.volumioRemoveToBrowseSources (/volumio/app/index.js:261:28) Jun 15 07:50:15 volumio volumio[1141]: at ControllerCalmRadio.onStop (/data/plugins/music_service/calmradio/index.js:173:21) Jun 15 07:50:15 volumio volumio[1141]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jun 15 07:50:15 volumio volumio[1141]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Jun 15 07:50:15 volumio volumio[1141]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Jun 15 07:50:15 volumio volumio[1141]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Jun 15 07:50:15 volumio volumio[1141]: at Socket.emit (events.js:400:28) Jun 15 07:50:15 volumio volumio[1141]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 15 07:50:15 volumio volumio[1141]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Jun 15 07:50:15 volumio volumio[1141]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 15 07:50:15 volumio sudo[4760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-15 07:49 Jun 15 07:50:15 volumio sudo[4760]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"