Feb 26 12:07:02 volumio ntpd[886]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 26 12:08:14 volumio volumio[953]: 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: 9 Feb 26 12:08:14 volumio volumio[953]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 26 12:08:14 volumio volumio[953]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/now_playing.zip Feb 26 12:08:14 volumio volumio[953]: info: Folder /tmp/plugins removed Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.218Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.216Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.822Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio[953]: info: Check plugin dependencies Feb 26 12:08:14 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.850Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio[953]: info: Checking if plugin already exists Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.855Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio[953]: info: Rename folder Feb 26 12:08:14 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:14.869Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:14 volumio volumio[953]: info: Move to category Feb 26 12:08:14 volumio volumio[953]: info: Folder /tmp/downloaded_plugin.zip removed Feb 26 12:08:15 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:15.150Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:15 volumio volumio[953]: info: Checking if install.sh is present Feb 26 12:08:15 volumio volumio[953]: info: Executing install.sh Feb 26 12:08:15 volumio sudo[3402]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Feb 26 12:08:15 volumio sudo[3402]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 12:08:15 volumio volumio[953]: info: Installing geo-tz node dependency Feb 26 12:08:15 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:15.231Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio[953]: info: Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.197Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio[953]: info: changed 7 packages, and audited 164 packages in 3s Feb 26 12:08:18 volumio volumio[953]: info: Feb 26 12:08:18 volumio volumio[953]: info: 22 packages are looking for funding Feb 26 12:08:18 volumio volumio[953]: info: run `npm fund` for details Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.203Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.223Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.224Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.225Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio[953]: info: Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.228Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio[953]: info: 7 vulnerabilities (1 low, 1 moderate, 5 high) Feb 26 12:08:18 volumio volumio[953]: info: Feb 26 12:08:18 volumio volumio[953]: info: To address all issues, run: Feb 26 12:08:18 volumio volumio[953]: info: npm audit fix Feb 26 12:08:18 volumio volumio[953]: info: Feb 26 12:08:18 volumio volumio[953]: info: Run `npm audit` for details. Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.243Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.244Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.245Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.247Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.247Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:18 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:18.247Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.910Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: up to date, audited 164 packages in 1s Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.919Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.931Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: 22 packages are looking for funding Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.941Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: run `npm fund` for details Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.944Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.954Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio[953]: info: 7 vulnerabilities (1 low, 1 moderate, 5 high) Feb 26 12:08:19 volumio volumio[953]: info: Feb 26 12:08:19 volumio volumio[953]: info: To address all issues, run: Feb 26 12:08:19 volumio volumio[953]: info: npm audit fix Feb 26 12:08:19 volumio volumio[953]: info: Feb 26 12:08:19 volumio volumio[953]: info: Run `npm audit` for details. Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.979Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.981Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:19 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:19.982Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:20 volumio volumio[953]: info: Creating user directories (if not exist) Feb 26 12:08:20 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:20.005Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:20 volumio sudo[3402]: pam_unix(sudo:session): session closed for user root Feb 26 12:08:20 volumio volumio[953]: info: Now Playing plugin installed Feb 26 12:08:20 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:20.021Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:20 volumio volumio[953]: Plugin install end detected on script Feb 26 12:08:20 volumio volumio[953]: info: Install script completed Feb 26 12:08:20 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:20.026Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:20 volumio volumio[953]: info: Adding reference to registry Feb 26 12:08:20 volumio volumio5-onboarding[1558]: time=2026-02-26T07:08:20.033Z level=WARN msg="received plugin install progress but no plugin is being installed" component=state Feb 26 12:08:20 volumio volumio[953]: info: Done installing plugin. Feb 26 12:08:20 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 12:08:20 volumio volumio[953]: info: Folder /tmp/plugins removed Feb 26 12:08:20 volumio volumio[953]: info: Folder /tmp/downloaded_plugin.zip removed Feb 26 12:08:20 volumio volumio[953]: info: Folder /data/temp removed Feb 26 12:08:29 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Feb 26 12:08:29 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , setTOSAccepted Feb 26 12:08:30 volumio sudo[3494]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 12:08:30 volumio sudo[3494]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 12:08:30 volumio sudo[3494]: pam_unix(sudo:session): session closed for user root Feb 26 12:08:30 volumio sudo[3496]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 12:08:30 volumio sudo[3496]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 12:08:30 volumio sudo[3496]: pam_unix(sudo:session): session closed for user root Feb 26 12:08:30 volumio volumio[953]: 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: 8 Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:31 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 12:08:31 volumio volumio[953]: info: Received Get System Info Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 12:08:31 volumio volumio[953]: info: Discovery: Getting this device information Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:31 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:31 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:31 volumio volumio[953]: info: Listing playlists Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 26 12:08:31 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:31 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 12:08:32 volumio volumio[953]: info: Received Get System Info Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 12:08:32 volumio volumio[953]: info: Discovery: Getting this device information Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:32 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:32 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 12:08:33 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 12:08:36 volumio volumio[953]: info: CoreCommandRouter::Close All Modals sent Feb 26 12:08:38 volumio volumio[953]: info: Enabling plugin now_playing Feb 26 12:08:38 volumio volumio[953]: info: Loading plugin "now_playing"... Feb 26 12:08:38 volumio volumio[953]: info: PLUGIN START: now_playing Feb 26 12:08:38 volumio volumio[953]: 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 26 12:08:38 volumio volumio[953]: info: [now-playing] ConfigUpdater: updated config version to 0.7.6 Feb 26 12:08:38 volumio volumio[953]: info: [now-playing] App is listening on port 4004. Feb 26 12:08:38 volumio volumio[953]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Feb 26 12:08:38 volumio volumio[953]: info: Done. Feb 26 12:08:39 volumio volumio[953]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 12:08:39 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 12:08:39 volumio volumio[953]: info: Discovery: Getting this device information Feb 26 12:08:39 volumio volumio[953]: info: CoreCommandRouter::volumioGetState Feb 26 12:08:39 volumio volumio[953]: info: CorePlayQueue::getTrack 0 Feb 26 12:08:39 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 12:08:40 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 26 12:08:45 volumio volumio[953]: info: CALLMETHOD: user_interface now_playing configSaveLocalizationSettings [object Object] Feb 26 12:08:45 volumio volumio[953]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveLocalizationSettings Feb 26 12:08:45 volumio volumio[953]: info: [now-playing] Fetching API key... Feb 26 12:08:46 volumio volumio[953]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 12:08:46 volumio volumio[953]: Error: Could not obtain API key Feb 26 12:08:46 volumio volumio[953]: at doGet (/data/plugins/user_interface/now_playing/dist/lib/api/openweathermap/index.js:125:19) Feb 26 12:08:46 volumio volumio[953]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 26 12:08:46 volumio volumio[953]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 12:08:46 volumio sudo[3543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 12:07' Feb 26 12:08:46 volumio sudo[3543]: 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"