Mar 25 11:42:01 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:01.128+08:00 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.5.179:59172 error="websocket: close 1006 (abnormal closure): unexpected EOF" Mar 25 11:42:01 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:01.128+08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.5.179:59172 Mar 25 11:42:01 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:01.128+08:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.5.179:59172 Mar 25 11:42:02 volumio dbus-daemon[854]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.19" (uid=0 pid=2195 comm="/usr/bin/volumio5-onboarding") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.3" (uid=0 pid=880 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.363+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.5.179:34956 Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.396+08:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=370.94587ms timeout=10s Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.396+08:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.396+08:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=370.937306ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 25 11:42:02 volumio volumio[1366]: info: Received Get System Info Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:42:02 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:02 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.398+08:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" name=Volumio Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.398+08:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" language=zh Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.400+08:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" timezone=Asia/Shanghai Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.401+08:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" available=true connected=true macAddress=7c:d3:0a:34:2c:7b ip4Address=192.168.5.224/24 ip6Address= Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.403+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.404+08:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" setupComplete=true Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 7 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: amixer -c 7 info | grep "Loopback" Mar 25 11:42:02 volumio volumio[1366]: Card sysdefault:7 'Loopback'/'Loopback 1' Mar 25 11:42:02 volumio volumio[1366]: Mixer name : 'Loopback Mixer' Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: amixer -c 5 info | grep "A" Mar 25 11:42:02 volumio volumio[1366]: Card sysdefault:5 'A'/'EROS A at usb-0000:00:12.0-1.4, high speed' Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 1 -D 0 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 1 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 1 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 7 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 7","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 7\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 3 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 3","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 3\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Mar 25 11:42:02 volumio volumio[1366]: /bin/sh: 1: /usr/local/bin/alsacap: not found Mar 25 11:42:02 volumio volumio[1366]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Mar 25 11:42:02 volumio volumio[1366]: amixer -c 5 info | grep "A" Mar 25 11:42:02 volumio volumio[1366]: Card sysdefault:5 'A'/'EROS A at usb-0000:00:12.0-1.4, high speed' Mar 25 11:42:02 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:02.530+08:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" selectedOutputId=5 Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:42:02 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:02 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:42:02 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.5.224:3000 from 192.168.5.179 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 11:42:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 11:42:03 volumio rfkill[19955]: unblock set for type bluetooth Mar 25 11:42:03 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:03.918+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.179:59172 @ 0xc0005dfda0" latency=432.317232ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Mar 25 11:42:03 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:03.918+08:00 level=ERROR msg="failed to send response" component=server dst=" @ 0xc0005dfda0" id=2069726235 status=STATUS_OK error="no addresses to write to" Mar 25 11:42:05 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:05 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:05 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:05.533+08:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" state=STATUS_STOPPED positionMs=1296 volume=100 Mar 25 11:42:05 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:05.533+08:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" id="music-library/NAS/MusicHD/单曲/未按歌星分文件夹/伍佰 & China Blue-世界第一等.flac" title=世界第一等 Mar 25 11:42:06 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:06.063+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:59172 @ 0xc0005dfda0" latency=371.076932ms timeout=10s endpoint=http://plugins.volumio.org duration=9.999828282s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Mar 25 11:42:06 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:06.063+08:00 level=ERROR msg="failed to send response" component=server dst=" @ 0xc0005dfda0" id=1554576494 status=STATUS_OK error="no addresses to write to" Mar 25 11:42:11 volumio rfkill[19966]: unblock set for type bluetooth Mar 25 11:42:11 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:11.832+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=411.957614ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Mar 25 11:42:11 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:11.888+08:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=395.67502ms timeout=1m0s Mar 25 11:42:15 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:15.096+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" networks=8 Mar 25 11:42:15 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:15.096+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" networks=8 Mar 25 11:42:15 volumio volumio[1366]: info: Received Get System Info Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:42:15 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:15 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:42:15 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:15.600+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:15 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:15.600+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 11:42:15 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 11:42:16 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:16.520+08:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.381+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.600+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=218.794422ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.609+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=227.099148ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.720+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=http://pushupdates.volumio.org duration=338.553254ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.722+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://functions.volumio.cloud duration=339.886597ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.725+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://functions.volumio.cloud duration=342.906976ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.754+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=372.258951ms Mar 25 11:42:17 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:17.983+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://www.googleapis.com duration=601.063892ms Mar 25 11:42:18 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:18.057+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=675.429044ms Mar 25 11:42:18 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:18.060+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://securetoken.googleapis.com duration=677.568961ms Mar 25 11:42:18 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:18.084+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://google.com duration=701.836435ms Mar 25 11:42:18 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:18.246+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=https://database.volumio.cloud duration=864.125456ms Mar 25 11:42:22 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:22.387+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=http://cddb.volumio.org duration=5.005520883s error="Head \"http://cddb.volumio.org\": EOF" Mar 25 11:42:27 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:27.383+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=371.777997ms timeout=10s endpoint=http://plugins.volumio.org duration=10.001300265s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Mar 25 11:42:36 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:36.404+08:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=386.512701ms timeout=1m0s Mar 25 11:42:39 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:39.573+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" networks=12 Mar 25 11:42:39 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:39.573+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" networks=12 Mar 25 11:42:40 volumio volumio[1366]: info: Received Get System Info Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:42:40 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:40 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:42:40 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:40.077+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:40 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:40.077+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.179:34956 @ 0xc0003f9b30" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 11:42:40 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 11:42:40 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:40.966+08:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 11:42:41 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:41.611+08:00 level=INFO msg="connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=406.423622ms timeout=1m0s ssid=popo100 Mar 25 11:42:41 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:41.612+08:00 level=INFO msg="wpa_supplicant control socket not functional, starting temporary daemon" component=volumio reason="dial unixgram /tmp/wpa_supplicant3249485805->/var/run/wpa_supplicant/wlan0: connect: no such file or directory" Mar 25 11:42:41 volumio wpa_supplicant[20047]: Successfully initialized wpa_supplicant Mar 25 11:42:41 volumio wpa_supplicant[20047]: nl80211: kernel reports: Match already configured Mar 25 11:42:41 volumio wpa_supplicant[20047]: nl80211: Could not configure driver mode Mar 25 11:42:41 volumio wpa_supplicant[20047]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Mar 25 11:42:41 volumio wpa_supplicant[20047]: wlan0: Failed to initialize driver interface Mar 25 11:42:41 volumio wpa_supplicant[20047]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 25 11:42:42 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:42.116+08:00 level=ERROR msg="failed to connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=406.423622ms timeout=1m0s ssid=popo100 error="failed to create wpa_supplicant daemon control: failed to create wpa_supplicant control: dial unixgram /tmp/wpa_supplicant3795249247->/var/run/wpa_supplicant/wlan0: connect: no such file or directory" Mar 25 11:42:46 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:46.565+08:00 level=INFO msg="connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=383.763399ms timeout=1m0s ssid=popo100 Mar 25 11:42:46 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:46.566+08:00 level=INFO msg="wpa_supplicant control socket not functional, starting temporary daemon" component=volumio reason="dial unixgram /tmp/wpa_supplicant2277779072->/var/run/wpa_supplicant/wlan0: connect: no such file or directory" Mar 25 11:42:46 volumio wpa_supplicant[20067]: Successfully initialized wpa_supplicant Mar 25 11:42:46 volumio wpa_supplicant[20067]: nl80211: kernel reports: Match already configured Mar 25 11:42:46 volumio wpa_supplicant[20067]: nl80211: Could not configure driver mode Mar 25 11:42:46 volumio wpa_supplicant[20067]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Mar 25 11:42:46 volumio wpa_supplicant[20067]: wlan0: Failed to initialize driver interface Mar 25 11:42:46 volumio wpa_supplicant[20067]: wlan0: CTRL-EVENT-DSCP-POLICY clear_all Mar 25 11:42:47 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:47.071+08:00 level=ERROR msg="failed to connect to WiFi" component=server type=REQUEST_TYPE_CONNECT_TO_WIFI peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=383.763399ms timeout=1m0s ssid=popo100 error="failed to create wpa_supplicant daemon control: failed to create wpa_supplicant control: dial unixgram /tmp/wpa_supplicant3460301693->/var/run/wpa_supplicant/wlan0: connect: no such file or directory" Mar 25 11:42:55 volumio ntpd[1157]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 25 11:42:55 volumio ntpd[1157]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Mar 25 11:42:55 volumio ntpd[1157]: DNS: Pool skipping: 198.18.5.216 Mar 25 11:42:55 volumio ntpd[1157]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Mar 25 11:42:55 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:55.760+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=384.13881ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Mar 25 11:42:55 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:55.878+08:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.5.179:34956 @ 0xc0003f9b30" latency=375.083493ms timeout=1m0s Mar 25 11:42:56 volumio ntpd[1157]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 25 11:42:56 volumio ntpd[1157]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Mar 25 11:42:56 volumio ntpd[1157]: DNS: Pool skipping: 198.18.5.218 Mar 25 11:42:56 volumio ntpd[1157]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Mar 25 11:42:57 volumio ntpd[1157]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 25 11:42:57 volumio ntpd[1157]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Mar 25 11:42:57 volumio ntpd[1157]: DNS: Pool skipping: 198.18.5.219 Mar 25 11:42:57 volumio ntpd[1157]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Mar 25 11:42:57 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:57.889+08:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.5.179:34956 Mar 25 11:42:57 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:57.889+08:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.5.179:34956 Mar 25 11:42:57 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:57.893+08:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.5.179:50074 Mar 25 11:42:58 volumio ntpd[1157]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Mar 25 11:42:58 volumio ntpd[1157]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Mar 25 11:42:58 volumio ntpd[1157]: DNS: Pool skipping: 198.18.5.220 Mar 25 11:42:58 volumio ntpd[1157]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Mar 25 11:42:59 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:59.029+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" networks=10 Mar 25 11:42:59 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:59.029+08:00 level=ERROR msg="failed to send response" component=server dst=" @ 0xc0003f9b30" id=1213840754 status=STATUS_OK error="no addresses to write to" Mar 25 11:42:59 volumio volumio[1366]: info: Received Get System Info Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:42:59 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:42:59 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:42:59 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:59.533+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:59 volumio volumio5-onboarding[2195]: time=2026-03-25T11:42:59.534+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.179:50074 @ 0xc0005df5f0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 11:42:59 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 11:43:00 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:00.295+08:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 11:43:03 volumio sudo[20120]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 11:43:03 volumio sudo[20120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:03 volumio sudo[20120]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:03 volumio sudo[20122]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 11:43:03 volumio sudo[20122]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:03 volumio sudo[20122]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:03 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.5.224 from 192.168.5.179 UA: Mozilla/5.0 (Linux; Android 16; V2366HA Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:03 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 11:43:03 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:03 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:03 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:03 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:03 volumio volumio[1366]: info: Listing playlists Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 11:43:03 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 11:43:04 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:04 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:04 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 25 11:43:04 volumio volumio[1366]: info: Received Get System Version Mar 25 11:43:04 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 11:43:04 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:04.182+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.5.179:50074 @ 0xc0005df5f0" latency=385.623593ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_SETUP_V1_INTERNET Mar 25 11:43:04 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:04.236+08:00 level=INFO msg="run WiFi scan" component=server type=REQUEST_TYPE_RUN_WIFI_SCAN peer="192.168.5.179:50074 @ 0xc0005df5f0" latency=374.482639ms timeout=1m0s Mar 25 11:43:04 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 11:43:04 volumio sudo[20138]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 11:43:04 volumio sudo[20138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:04 volumio sudo[20138]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:04 volumio sudo[20140]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 11:43:04 volumio sudo[20140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:04 volumio sudo[20140]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:04 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.5.224 from 192.168.5.179 UA: Mozilla/5.0 (Linux; Android 16; V2366HA Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:05 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 11:43:05 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:05 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:05 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:05 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:05 volumio volumio[1366]: info: Listing playlists Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 25 11:43:05 volumio volumio[1366]: info: Received Get System Version Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 11:43:05 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 11:43:06 volumio sudo[20154]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 25 11:43:06 volumio sudo[20154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:06 volumio sudo[20154]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:06 volumio sudo[20156]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 25 11:43:06 volumio sudo[20156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 11:43:06 volumio sudo[20156]: pam_unix(sudo:session): session closed for user root Mar 25 11:43:06 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.5.224 from 192.168.5.179 UA: Mozilla/5.0 (Linux; Android 16; V2366HA Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.179 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:06 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 25 11:43:06 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:06 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:06 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:06 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:06 volumio volumio[1366]: info: Listing playlists Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Mar 25 11:43:06 volumio volumio[1366]: info: Received Get System Version Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 11:43:06 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 25 11:43:07 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:07.313+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" networks=8 Mar 25 11:43:07 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:07.313+08:00 level=INFO msg="emitting wifi scan event" component=server peer="192.168.5.179:50074 @ 0xc0005df5f0" networks=8 Mar 25 11:43:07 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:07 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:07 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:07 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:07.815+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.141:33108 @ 0xc00018fb00" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:43:07 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:07.815+08:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.5.179:50074 @ 0xc0005df5f0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 25 11:43:07 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 11:43:08 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:08 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:08 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:08 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 25 11:43:08 volumio volumio5-onboarding[2195]: time=2026-03-25T11:43:08.580+08:00 level=INFO msg="service successfully established" component=discovery/localnet Mar 25 11:43:10 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 11:43:10 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:10 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:10 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:10 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:10 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:10 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:10 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 11:43:12 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:12 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:12 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:12 volumio volumio[1366]: info: CoreCommandRouter::volumioGetQueue Mar 25 11:43:12 volumio volumio[1366]: info: CoreStateMachine::getQueue Mar 25 11:43:12 volumio volumio[1366]: info: CorePlayQueue::getQueue Mar 25 11:43:13 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 25 11:43:13 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:13 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:13 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:13 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:13 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:13 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:13 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:16 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 25 11:43:19 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Mar 25 11:43:19 volumio volumio[1366]: info: CURURI: artists:// Mar 25 11:43:19 volumio volumio[1366]: info: Preload queue cleared Mar 25 11:43:23 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 25 11:43:23 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 25 11:43:23 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 25 11:43:25 volumio volumio[1366]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 25 11:43:25 volumio volumio[1366]: info: Received Get System Version Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 25 11:43:25 volumio volumio[1366]: info: Received Get System Info Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 25 11:43:25 volumio volumio[1366]: info: Discovery: Getting this device information Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Mar 25 11:43:25 volumio volumio[1366]: info: CorePlayQueue::getTrack 0 Mar 25 11:43:25 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 25 11:43:26 volumio volumio[1366]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 11:43:26 volumio volumio[1366]: Error: Client network socket disconnected before secure TLS connection was established Mar 25 11:43:26 volumio volumio[1366]: at connResetException (node:internal/errors:720:14) Mar 25 11:43:26 volumio volumio[1366]: at TLSSocket.onConnectEnd (node:_tls_wrap:1682:19) Mar 25 11:43:26 volumio volumio[1366]: at TLSSocket.emit (node:events:526:35) Mar 25 11:43:26 volumio volumio[1366]: at endReadableNT (node:internal/streams/readable:1376:12) Mar 25 11:43:26 volumio volumio[1366]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Mar 25 11:43:26 volumio volumio[1366]: code: 'ECONNRESET', Mar 25 11:43:26 volumio volumio[1366]: path: null, Mar 25 11:43:26 volumio volumio[1366]: host: 'cdn-images.dzcdn.net', Mar 25 11:43:26 volumio volumio[1366]: port: 443, Mar 25 11:43:26 volumio volumio[1366]: localAddress: undefined Mar 25 11:43:26 volumio volumio[1366]: } Mar 25 11:43:26 volumio volumio[1366]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 11:43:26 volumio sudo[20259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 11:42' Mar 25 11:43:26 volumio sudo[20259]: 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 17:11:42 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="21a5161aef4cb85a1f93b140ccadc4f1"