Oct 02 02:33:40 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Oct 02 02:33:40 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Oct 02 02:33:40 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Oct 02 02:33:40 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Oct 02 02:33:40 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Oct 02 02:33:40 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Oct 02 02:33:40 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Oct 02 02:33:44 volumio volumio[1240]: info: Upmpdcli Daemon Started Oct 02 02:33:44 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection reader error: Oct 02 02:33:44 volumio volumio[1240]: (TypeError) terminated Oct 02 02:33:44 volumio volumio[1240]: TypeError: terminated Oct 02 02:33:44 volumio volumio[1240]: at Fetch.onAborted (node:internal/deps/undici/undici:11442:53) Oct 02 02:33:44 volumio volumio[1240]: at Fetch.emit (node:events:514:28) Oct 02 02:33:44 volumio volumio[1240]: at Fetch.terminate (node:internal/deps/undici/undici:10695:14) Oct 02 02:33:44 volumio volumio[1240]: at Object.onError (node:internal/deps/undici/undici:11537:36) Oct 02 02:33:44 volumio volumio[1240]: at Request.onError (node:internal/deps/undici/undici:8310:31) Oct 02 02:33:44 volumio volumio[1240]: at errorRequest (node:internal/deps/undici/undici:10378:17) Oct 02 02:33:44 volumio volumio[1240]: at TLSSocket.onSocketClose (node:internal/deps/undici/undici:9811:9) Oct 02 02:33:44 volumio volumio[1240]: at TLSSocket.emit (node:events:526:35) Oct 02 02:33:44 volumio volumio[1240]: at node:net:337:12 Oct 02 02:33:44 volumio volumio[1240]: at TCP.done (node:_tls_wrap:631:7) Oct 02 02:33:44 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection reader error: Oct 02 02:33:44 volumio volumio[1240]: (TypeError) terminated Oct 02 02:33:44 volumio volumio[1240]: TypeError: terminated Oct 02 02:33:44 volumio volumio[1240]: at Fetch.onAborted (node:internal/deps/undici/undici:11442:53) Oct 02 02:33:44 volumio volumio[1240]: at Fetch.emit (node:events:514:28) Oct 02 02:33:44 volumio volumio[1240]: at Fetch.terminate (node:internal/deps/undici/undici:10695:14) Oct 02 02:33:44 volumio volumio[1240]: at Object.onError (node:internal/deps/undici/undici:11537:36) Oct 02 02:33:44 volumio volumio[1240]: at Request.onError (node:internal/deps/undici/undici:8310:31) Oct 02 02:33:44 volumio volumio[1240]: at errorRequest (node:internal/deps/undici/undici:10378:17) Oct 02 02:33:44 volumio volumio[1240]: at TLSSocket.onSocketClose (node:internal/deps/undici/undici:9811:9) Oct 02 02:33:44 volumio volumio[1240]: at TLSSocket.emit (node:events:526:35) Oct 02 02:33:44 volumio volumio[1240]: at node:net:337:12 Oct 02 02:33:44 volumio volumio[1240]: at TCP.done (node:_tls_wrap:631:7) Oct 02 02:33:46 volumio volumio[1240]: info: Discovery: A device disappeared from network Oct 02 02:33:47 volumio volumio[1240]: info: Discovery: A device disappeared from network Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 02:33:56 volumio volumio[1240]: error: MyVolumio Plugin failed to start in a timely fashion Oct 02 02:33:56 volumio volumio[1240]: [Metrics] CommandRouter: 45s 988.85ms Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::volumiosetStartupVolume Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::Close All Modals sent Oct 02 02:33:56 volumio volumio[1240]: info: CoreCommandRouter::Close All Modals sent Oct 02 02:33:57 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:57.544-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.103:60802 @ 0x1cc2780" latency=7.365322ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 02:33:57 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:57.571-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s Oct 02 02:33:57 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:57.976-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=405.306707ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.057-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=460.191205ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.136-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=http://pushupdates.volumio.org duration=553.36745ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.175-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://functions.volumio.cloud duration=592.942677ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.186-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://www.googleapis.com duration=615.307576ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.223-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=651.273952ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.330-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=759.101914ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.336-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://securetoken.googleapis.com duration=752.193231ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.382-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://functions.volumio.cloud duration=810.273932ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.430-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://google.com duration=859.052193ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.571-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=http://plugins.volumio.org duration=988.026739ms Oct 02 02:33:58 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:58.759-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=https://database.volumio.cloud duration=1.173336377s Oct 02 02:33:59 volumio volumio5-onboarding[1577]: time=2026-10-02T02:33:59.618-07:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-6.823365ms timeout=10s endpoint=http://cddb.volumio.org duration=2.046235804s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 02:34:02 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Oct 02 02:34:02 volumio kernel: xhci_hcd 0000:01:00.0: @000000040cd060d0 00000000 00000000 0e000000 02028000 Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.309-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.103:60802 @ 0x1cc2780" latency=120.521969ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.328-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.365-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=36.442098ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.374-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=45.535113ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.385-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=57.566609ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.505-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://www.googleapis.com duration=176.935696ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.520-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://google.com duration=192.37432ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.559-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=http://pushupdates.volumio.org duration=229.723103ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.581-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=252.119225ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.586-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://securetoken.googleapis.com duration=258.172297ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.612-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://database.volumio.cloud duration=282.181066ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.617-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://functions.volumio.cloud duration=287.959508ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.824-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=http://plugins.volumio.org duration=494.62036ms Oct 02 02:34:02 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:02.832-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=https://functions.volumio.cloud duration=503.14732ms Oct 02 02:34:03 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection error: Oct 02 02:34:03 volumio volumio[1240]: (TypeError) fetch failed Oct 02 02:34:03 volumio volumio[1240]: TypeError: fetch failed Oct 02 02:34:03 volumio volumio[1240]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 02 02:34:03 volumio volumio[1240]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 02 02:34:03 volumio volumio[1240]: at runNextTicks (node:internal/process/task_queues:64:3) Oct 02 02:34:03 volumio volumio[1240]: at process.processImmediate (node:internal/timers:449:9) Oct 02 02:34:03 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:78:20) Oct 02 02:34:03 volumio volumio[1240]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:143:17 Oct 02 02:34:03 volumio volumio[1240]: error: [yt-cast-receiver] Retrying 1 / 3 Oct 02 02:34:03 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection error: Oct 02 02:34:03 volumio volumio[1240]: (TypeError) fetch failed Oct 02 02:34:03 volumio volumio[1240]: TypeError: fetch failed Oct 02 02:34:03 volumio volumio[1240]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 02 02:34:03 volumio volumio[1240]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 02 02:34:03 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:78:20) Oct 02 02:34:03 volumio volumio[1240]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:143:17 Oct 02 02:34:03 volumio volumio[1240]: error: [yt-cast-receiver] Retrying 1 / 3 Oct 02 02:34:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 02 02:34:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 02:34:03 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 02 02:34:04 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:04.093-07:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.103:60802 @ 0x1cc2780" latency=-7.902402ms timeout=10s endpoint=http://cddb.volumio.org duration=1.764395479s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 02:34:04 volumio volumio[1240]: info: BOOT COMPLETED Oct 02 02:34:08 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:08.587-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.68.103:60802 error="websocket: close 1006 (abnormal closure): unexpected EOF" Oct 02 02:34:08 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:08.588-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.68.103:60802 Oct 02 02:34:08 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:08.588-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.68.103:60802 Oct 02 02:34:11 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:11.265-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.103:61126 Oct 02 02:34:11 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:11.325-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61126 @ 0x21609c0" latency=-4.926715ms timeout=20s Oct 02 02:34:11 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:11.325-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.103:61126 @ 0x21609c0" Oct 02 02:34:11 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:11.325-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.103:61126 @ 0x21609c0" latency=-3.972623ms platform=PLATFORM_IOS version=6.260807.0 Oct 02 02:34:12 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:12.043-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61126 @ 0x21609c0" latency=-3.170658ms timeout=20s Oct 02 02:34:12 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:12.043-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.103:61126 @ 0x21609c0" Oct 02 02:34:12 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:12.044-07:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.103:61126 @ 0x21609c0" latency=-1.749121ms platform=PLATFORM_IOS version=6.260807.0 Oct 02 02:34:19 volumio volumio[1240]: info: Discovery: adding f526cb3f-ab46-4ad8-8761-7f2b9093b7e7 Oct 02 02:34:19 volumio volumio[1240]: info: Discovery: Found device Volumio Oct 02 02:34:19 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:19 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.416-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.68.103:61126 Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.416-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.68.103:61126 Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.428-07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.103:61554 Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.468-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61554 @ 0x222d860" latency=-7.746458ms timeout=20s Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.468-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.468-07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61554 @ 0x222d860" latency=-6.296662ms timeout=20s Oct 02 02:34:24 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:24.468-07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" Oct 02 02:34:31 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:31.325-07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61126 @ 0x21609c0" latency=-4.926715ms timeout=20s error="failed to get system info: could not get system info: context deadline exceeded" Oct 02 02:34:31 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:31.325-07:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x21609c0" id=2126970525 status=STATUS_UNKNOWN error="no addresses to write to" Oct 02 02:34:32 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:32.044-07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61126 @ 0x21609c0" latency=-3.170658ms timeout=20s error="failed to get system info: could not get system info: context deadline exceeded" Oct 02 02:34:32 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:32.044-07:00 level=ERROR msg="failed to send response" component=server dst=" @ 0x21609c0" id=421368554 status=STATUS_UNKNOWN error="no addresses to write to" Oct 02 02:34:32 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:32.642-07:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.103:61554 @ 0x222d860" latency=-6.327058ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 02 02:34:36 volumio volumio[1240]: info: Received Get System Info Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 02:34:36 volumio volumio[1240]: info: Discovery: Getting this device information Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:36 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 02:34:36 volumio volumio[1240]: info: Received Get System Info Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 02:34:36 volumio volumio[1240]: info: Discovery: Getting this device information Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:36 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 02:34:36 volumio volumio[1240]: info: Received Get System Info Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 02:34:36 volumio volumio[1240]: info: Discovery: Getting this device information Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:36 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 02:34:36 volumio volumio[1240]: info: Received Get System Info Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 02:34:36 volumio volumio[1240]: info: Discovery: Getting this device information Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:36 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:36 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 02:34:36 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:36.137-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" name=Volumio Oct 02 02:34:36 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:36.138-07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" name=Volumio Oct 02 02:34:36 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:36.138-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" language=en Oct 02 02:34:36 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:36.138-07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.68.103:61554 @ 0x222d860" language=en Oct 02 02:34:36 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection error: Oct 02 02:34:36 volumio volumio[1240]: (TypeError) fetch failed Oct 02 02:34:36 volumio volumio[1240]: TypeError: fetch failed Oct 02 02:34:36 volumio volumio[1240]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 02 02:34:36 volumio volumio[1240]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 02 02:34:36 volumio volumio[1240]: at runNextTicks (node:internal/process/task_queues:64:3) Oct 02 02:34:36 volumio volumio[1240]: at process.processImmediate (node:internal/timers:449:9) Oct 02 02:34:36 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:78:20) Oct 02 02:34:36 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:90:20) Oct 02 02:34:36 volumio volumio[1240]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:143:17 Oct 02 02:34:36 volumio volumio[1240]: error: [yt-cast-receiver] Retrying 2 / 3 Oct 02 02:34:44 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:44.470-07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61554 @ 0x222d860" latency=-6.296662ms timeout=20s error="failed to get current timezone: could not get current timezone: context deadline exceeded" Oct 02 02:34:44 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:44.470-07:00 level=ERROR msg="failed to notify all device data" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.103:61554 @ 0x222d860" latency=-7.746458ms timeout=20s error="failed to get current timezone: could not get current timezone: context deadline exceeded" Oct 02 02:34:46 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:46.916-07:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.68.103:61554 error="read tcp 192.168.68.110:7331->192.168.68.103:61554: read: connection reset by peer" Oct 02 02:34:46 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:46.916-07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.68.103:61554 Oct 02 02:34:46 volumio volumio5-onboarding[1577]: time=2026-10-02T02:34:46.916-07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.68.103:61554 Oct 02 02:34:51 volumio volumio[1240]: info: Discovery: this is already registered, f526cb3f-ab46-4ad8-8761-7f2b9093b7e7 Oct 02 02:34:51 volumio volumio[1240]: info: Discovery: Found device Volumio Oct 02 02:34:51 volumio volumio[1240]: info: CoreCommandRouter::volumioGetState Oct 02 02:34:51 volumio volumio[1240]: info: CorePlayQueue::getTrack 0 Oct 02 02:34:51 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 02:34:51 volumio volumio[1240]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 02 02:34:51 volumio volumio[1240]: error: [yt-cast-receiver] RPC connection error: Oct 02 02:34:51 volumio volumio[1240]: (TypeError) fetch failed Oct 02 02:34:51 volumio volumio[1240]: TypeError: fetch failed Oct 02 02:34:51 volumio volumio[1240]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Oct 02 02:34:51 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:78:20) Oct 02 02:34:51 volumio volumio[1240]: at async RPCConnection._RPCConnection_doConnect (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:90:20) Oct 02 02:34:51 volumio volumio[1240]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/RPCConnection.js:143:17 Oct 02 02:34:51 volumio volumio[1240]: error: [yt-cast-receiver] Retrying 2 / 3 Oct 02 02:34:51 volumio volumio[1240]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 02:34:52 volumio volumio[1240]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Oct 02 02:34:52 volumio volumio[1240]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Oct 02 02:34:52 volumio volumio[1240]: at new NodeError (node:internal/errors:405:5) Oct 02 02:34:52 volumio volumio[1240]: at assert (node:internal/assert:14:11) Oct 02 02:34:52 volumio volumio[1240]: at internalConnectMultiple (node:net:1118:3) Oct 02 02:34:52 volumio volumio[1240]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Oct 02 02:34:52 volumio volumio[1240]: at listOnTimeout (node:internal/timers:575:11) Oct 02 02:34:52 volumio volumio[1240]: at process.processTimers (node:internal/timers:514:7) { Oct 02 02:34:52 volumio volumio[1240]: code: 'ERR_INTERNAL_ASSERTION' Oct 02 02:34:52 volumio volumio[1240]: } Oct 02 02:34:52 volumio volumio[1240]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 02:34:55 volumio sudo[1969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 02:33' Oct 02 02:34:55 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"