Feb 25 18:05:17 volumio volumio[965]: 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 25 18:05:17 volumio volumio[965]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 25 18:05:17 volumio volumio[965]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 25 18:05:17 volumio volumio[965]: info: Folder /tmp/plugins removed Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.215Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.215Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.783Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio[965]: info: Check plugin dependencies Feb 25 18:05:17 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.834Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio[965]: info: Checking if plugin already exists Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.836Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio[965]: info: Rename folder Feb 25 18:05:17 volumio volumio[965]: info: Folder /tmp/downloaded_plugin.zip removed Feb 25 18:05:17 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:17.858Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:17 volumio volumio[965]: info: Move to category Feb 25 18:05:20 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:20.799Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:20 volumio volumio[965]: info: Checking if install.sh is present Feb 25 18:05:20 volumio volumio[965]: info: Executing install.sh Feb 25 18:05:20 volumio sudo[4130]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Feb 25 18:05:20 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:05:20 volumio volumio[965]: info: Installing geo-tz node dependency Feb 25 18:05:20 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:20.871Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio[965]: info: Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.744Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio[965]: info: changed 8 packages, and audited 164 packages in 23s Feb 25 18:05:43 volumio volumio[965]: info: Feb 25 18:05:43 volumio volumio[965]: info: 22 packages are looking for funding Feb 25 18:05:43 volumio volumio[965]: info: run `npm fund` for details Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.750Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.750Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.751Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.751Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio[965]: info: Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.783Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio[965]: info: 7 vulnerabilities (1 low, 1 moderate, 5 high) Feb 25 18:05:43 volumio volumio[965]: info: Feb 25 18:05:43 volumio volumio[965]: info: To address all issues, run: Feb 25 18:05:43 volumio volumio[965]: info: npm audit fix Feb 25 18:05:43 volumio volumio[965]: info: Feb 25 18:05:43 volumio volumio[965]: info: Run `npm audit` for details. Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.810Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.810Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.815Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.816Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.822Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:43 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:43.822Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Feb 25 18:05:45 volumio volumio[965]: info: up to date, audited 164 packages in 1s Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.602Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.602Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.617Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: 22 packages are looking for funding Feb 25 18:05:45 volumio volumio[965]: info: run `npm fund` for details Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.622Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.622Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.635Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: 7 vulnerabilities (1 low, 1 moderate, 5 high) Feb 25 18:05:45 volumio volumio[965]: info: Feb 25 18:05:45 volumio volumio[965]: info: To address all issues, run: Feb 25 18:05:45 volumio volumio[965]: info: npm audit fix Feb 25 18:05:45 volumio volumio[965]: info: Feb 25 18:05:45 volumio volumio[965]: info: Run `npm audit` for details. Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.665Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.669Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Feb 25 18:05:45 volumio volumio[965]: info: Install script completed Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.700Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Adding reference to registry Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.707Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Done installing plugin. Feb 25 18:05:45 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 18:05:45 volumio volumio[965]: info: Folder /tmp/plugins removed Feb 25 18:05:45 volumio volumio[965]: info: Folder /tmp/downloaded_plugin.zip removed Feb 25 18:05:45 volumio volumio[965]: info: Folder /data/temp removed Feb 25 18:05:45 volumio volumio[965]: info: Creating user directories (if not exist) Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.732Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:05:45 volumio volumio[965]: info: Now Playing plugin installed Feb 25 18:05:45 volumio volumio[965]: Plugin install end detected on script Feb 25 18:05:45 volumio volumio5-onboarding[1575]: time=2026-02-25T18:05:45.737Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 25 18:06:07 volumio sudo[4376]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 18:06:07 volumio sudo[4376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:06:07 volumio sudo[4376]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:07 volumio sudo[4378]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 18:06:07 volumio sudo[4378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:06:08 volumio sudo[4378]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:08 volumio volumio[965]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 18:06:08 volumio sudo[4386]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 18:06:08 volumio sudo[4388]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 18:06:08 volumio sudo[4388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:06:08 volumio sudo[4386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 25 18:06:08 volumio sudo[4386]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:08 volumio sudo[4388]: pam_unix(sudo:session): session closed for user root Feb 25 18:06:08 volumio volumio[965]: verbose: New Socket.io Connection to 192.168.1.192 from 192.168.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:08 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 18:06:08 volumio volumio[965]: info: Received Get System Info Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 18:06:08 volumio volumio[965]: info: Discovery: Getting this device information Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:08 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:08 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:08 volumio volumio[965]: info: Listing playlists Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 18:06:08 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 18:06:09 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:09 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 18:06:10 volumio volumio[965]: info: Received Get System Info Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 18:06:10 volumio volumio[965]: info: Discovery: Getting this device information Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:10 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:10 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 18:06:11 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 18:06:12 volumio volumio[965]: info: CoreCommandRouter::Close All Modals sent Feb 25 18:06:14 volumio volumio[965]: info: Enabling plugin now_playing Feb 25 18:06:14 volumio volumio[965]: info: Loading plugin "now_playing"... Feb 25 18:06:15 volumio volumio[965]: info: PLUGIN START: now_playing Feb 25 18:06:15 volumio volumio[965]: info: [now-playing] ConfigUpdater: config version unavailable. Either this is the first time the plugin is installed, or the previous version is < 0.3.0). Config will not be updated. Feb 25 18:06:15 volumio volumio[965]: info: [now-playing] ConfigUpdater: updated config version to 0.7.6 Feb 25 18:06:15 volumio volumio[965]: info: [now-playing] App is listening on port 4004. Feb 25 18:06:15 volumio volumio[965]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 25 18:06:15 volumio volumio[965]: info: Done. Feb 25 18:06:16 volumio volumio[965]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 18:06:16 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 18:06:16 volumio volumio[965]: info: Discovery: Getting this device information Feb 25 18:06:16 volumio volumio[965]: info: CoreCommandRouter::volumioGetState Feb 25 18:06:16 volumio volumio[965]: info: CorePlayQueue::getTrack 0 Feb 25 18:06:16 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 18:06:18 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 18:06:19 volumio volumio[965]: info: CoreCommandRouter::Close All Modals sent Feb 25 18:06:19 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Feb 25 18:06:23 volumio volumio[965]: info: CALLMETHOD: user_interface now_playing configSaveLocalizationSettings [object Object] Feb 25 18:06:23 volumio volumio[965]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveLocalizationSettings Feb 25 18:06:23 volumio volumio[965]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:06:23 volumio volumio[965]: Error: required params are not set Feb 25 18:06:23 volumio volumio[965]: at fetchData (/data/plugins/user_interface/now_playing/dist/lib/api/yandexweather/index.js:88:23) Feb 25 18:06:23 volumio volumio[965]: at YandexWeatherAPI.getWeather (/data/plugins/user_interface/now_playing/dist/lib/api/yandexweather/index.js:90:51) Feb 25 18:06:23 volumio volumio[965]: at /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:310:82 Feb 25 18:06:23 volumio volumio[965]: at WeatherAPI._WeatherAPI_getFetchPromise (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:136:21) Feb 25 18:06:23 volumio volumio[965]: at WeatherAPI._WeatherAPI_doFetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:309:98) Feb 25 18:06:23 volumio volumio[965]: at /data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:123:197 Feb 25 18:06:23 volumio volumio[965]: at Cache.getOrSet (/data/plugins/user_interface/now_playing/dist/lib/utils/Cache.js:95:16) Feb 25 18:06:23 volumio volumio[965]: at WeatherAPI.fetchInfo (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:123:79) Feb 25 18:06:23 volumio volumio[965]: at WeatherAPI.setConfig (/data/plugins/user_interface/now_playing/dist/lib/api/WeatherAPI.js:102:18) Feb 25 18:06:23 volumio volumio[965]: at ControllerNowPlaying._ControllerNowPlaying_configureWeatherApi (/data/plugins/user_interface/now_playing/dist/index.js:1968:26) Feb 25 18:06:23 volumio volumio[965]: at ControllerNowPlaying.configSaveLocalizationSettings (/data/plugins/user_interface/now_playing/dist/index.js:364:119) Feb 25 18:06:23 volumio volumio[965]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 25 18:06:23 volumio volumio[965]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Feb 25 18:06:23 volumio volumio[965]: at Socket.emit (node:events:514:28) Feb 25 18:06:23 volumio volumio[965]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 25 18:06:23 volumio volumio[965]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 25 18:06:23 volumio volumio[965]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 18:06:23 volumio sudo[4440]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 18:05' Feb 25 18:06:23 volumio sudo[4440]: 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"