Apr 14 16:03:00 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:00 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:03:00 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:00 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:00 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:00 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:04 volumio sudo[2831]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 14 16:03:04 volumio sudo[2831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 16:03:04 volumio sudo[2831]: pam_unix(sudo:session): session closed for user root Apr 14 16:03:04 volumio sudo[2834]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 14 16:03:04 volumio sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 16:03:04 volumio sudo[2834]: pam_unix(sudo:session): session closed for user root Apr 14 16:03:04 volumio sudo[2837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 14 16:03:04 volumio sudo[2837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 14 16:03:05 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 14 16:03:09 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Apr 14 16:03:09 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:03:09 volumio systemd[1]: upmpdcli.service: Consumed 23.201s CPU time. Apr 14 16:03:09 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 14 16:03:09 volumio sudo[2837]: pam_unix(sudo:session): session closed for user root Apr 14 16:03:09 volumio volumio[1230]: info: Upmpdcli Daemon Started Apr 14 16:03:09 volumio upmpdcli[2875]: writing RSA key Apr 14 16:03:10 volumio volumio[1230]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Apr 14 16:03:10 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:10 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:13 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:13 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:13 volumio volumio[1230]: verbose: New Socket.io Connection to 192.168.1.4:3000 from 192.168.1.8 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 14 16:03:13 volumio volumio[1230]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 14 16:03:13 volumio volumio[1230]: info: Received Get System Version Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 14 16:03:13 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:13 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:13 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:13 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:21 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:21 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:29 volumio volumio5-onboarding[2817]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Apr 14 16:03:29 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:03:29 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 14 16:03:29 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 4. Apr 14 16:03:29 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 16:03:29 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.567+05:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 14 16:03:29 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 16:03:29 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.590+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.591+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.592+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.594+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.595+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.595+05:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 14 16:03:29 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:29 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:29 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.601+05:00 level=INFO msg="system info for a8404e662b33cd3c2745c5b231b0f180" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 14 16:03:29 volumio volumio5-onboarding[2939]: time=2026-04-14T16:03:29.658+05:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 14 16:03:29 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:29 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:29 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:29 volumio volumio-remote-updater[668]: Test mode disabled Apr 14 16:03:29 volumio volumio-remote-updater[668]: Alpha mode disabled Apr 14 16:03:29 volumio volumio-remote-updater[668]: Alpha legacy test mode disabled Apr 14 16:03:29 volumio volumio[1230]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:03:29 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 16:03:31 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:31 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:40 volumio volumio[1230]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Apr 14 16:03:41 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:41 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:51 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:03:51 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:03:55 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:55 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:58 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Apr 14 16:03:58 volumio volumio[1230]: info: CoreStateMachine::getQueue Apr 14 16:03:58 volumio volumio[1230]: info: CorePlayQueue::getQueue Apr 14 16:03:59 volumio volumio5-onboarding[2939]: failed to bootstrap state: failed to get music providers: could not get available plugins: could not get available plugins: context deadline exceeded Apr 14 16:03:59 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Apr 14 16:03:59 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Apr 14 16:03:59 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 5. Apr 14 16:03:59 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 16:03:59 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.826+05:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Apr 14 16:03:59 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 16:03:59 volumio volumio[1230]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 6 Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.887+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci0 error="bluetooth: adapter /org/bluez/hci0 does not exist" Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.889+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci1 error="bluetooth: adapter /org/bluez/hci1 does not exist" Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.891+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci2 error="bluetooth: adapter /org/bluez/hci2 does not exist" Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.893+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci3 error="bluetooth: adapter /org/bluez/hci3 does not exist" Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.895+05:00 level=WARN msg="failed to enable Bluetooth adapter" adapter=hci4 error="bluetooth: adapter /org/bluez/hci4 does not exist" Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.895+05:00 level=WARN msg="no Bluetooth adapter found, BLE discovery disabled" Apr 14 16:03:59 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:59 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:59 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.903+05:00 level=INFO msg="system info for a8404e662b33cd3c2745c5b231b0f180" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Apr 14 16:03:59 volumio volumio5-onboarding[3021]: time=2026-04-14T16:03:59.957+05:00 level=INFO msg="bootstrapping state" hasInternet=true Apr 14 16:03:59 volumio volumio[1230]: info: Received Get System Info Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 14 16:03:59 volumio volumio[1230]: info: Discovery: Getting this device information Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:03:59 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:03:59 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 14 16:03:59 volumio volumio-remote-updater[668]: Test mode disabled Apr 14 16:03:59 volumio volumio-remote-updater[668]: Alpha mode disabled Apr 14 16:03:59 volumio volumio-remote-updater[668]: Alpha legacy test mode disabled Apr 14 16:04:00 volumio volumio[1230]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 14 16:04:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 14 16:04:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:04:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 14 16:04:00 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 14 16:04:02 volumio kernel: EDID block 0 (tag 0x00) checksum is invalid, remainder is 20 Apr 14 16:04:02 volumio kernel: [drm:radeon_vga_detect [radeon]] *ERROR* VGA-1: probed a monitor but no|invalid EDID Apr 14 16:04:02 volumio volumio[1230]: info: CoreCommandRouter::volumioGetState Apr 14 16:04:02 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Apr 14 16:04:09 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 16:04:10 volumio volumio[1230]: info: Preload queue cleared Apr 14 16:04:11 volumio volumio[1230]: error: Cannot download Available plugins list: Error: ESOCKETTIMEDOUT Apr 14 16:04:11 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 14 16:04:11 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:04:11 volumio volumio[1230]: Error Apr 14 16:04:11 volumio volumio[1230]: at /volumio/app/plugins/music_service/webradio/index.js:427:31 Apr 14 16:04:11 volumio volumio[1230]: at Request.handleRequestResponse [as _callback] (/volumio/node_modules/unirest/index.js:444:15) Apr 14 16:04:11 volumio volumio[1230]: at self.callback (/volumio/node_modules/request/request.js:185:22) Apr 14 16:04:11 volumio volumio[1230]: at Request.emit (node:events:514:28) Apr 14 16:04:11 volumio volumio[1230]: at Request.onRequestError (/volumio/node_modules/request/request.js:877:8) Apr 14 16:04:11 volumio volumio[1230]: at ClientRequest.emit (node:events:514:28) Apr 14 16:04:11 volumio volumio[1230]: at Socket.socketErrorListener (node:_http_client:495:9) Apr 14 16:04:11 volumio volumio[1230]: at Socket.emit (node:events:514:28) Apr 14 16:04:11 volumio volumio[1230]: at emitErrorNT (node:internal/streams/destroy:151:8) Apr 14 16:04:11 volumio volumio[1230]: at emitErrorCloseNT (node:internal/streams/destroy:116:3) Apr 14 16:04:11 volumio volumio[1230]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Apr 14 16:04:11 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 16:04:11 volumio sudo[3073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 16:03' Apr 14 16:04:11 volumio sudo[3073]: 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"