-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Sun 2026-08-30 10:17:25 IST. -- Aug 30 10:16:06 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:06.412+05:30 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%03 @ 0x19a4de0" latency=73.254223ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 10:16:08 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:08.785+05:30 level=INFO msg="new address was allocated" component=ble/conn old=4 new=5 Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.116+05:30 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="00:00:00:00:00:00%04 @ 0x1765800" latency=131.556719ms timeout=20s Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.116+05:30 level=INFO msg="emitting device capabilities changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" Aug 30 10:16:09 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:09 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:09 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.181+05:30 level=INFO msg="emitting device name changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" name=Moojik Aug 30 10:16:09 moojik go-librespot[1242]: time="2026-08-30T10:16:09+05:30" level=trace msg="sent dealer ping" Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.236+05:30 level=INFO msg="emitting device language changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" language=en Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.299+05:30 level=INFO msg="emitting device timezone changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" timezone=Asia/Calcutta Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.357+05:30 level=INFO msg="emitting ethernet info changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" available=true connected=false macAddress= ip4Address= ip6Address= Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.420+05:30 level=INFO msg="emitting wifi info changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" available=true connected=true macAddress=b8:27:eb:67:b0:b8 ip4Address=192.168.68.111/24 ip6Address= ssid=JoaHome Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.475+05:30 level=INFO msg="emitting device setup status changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" setupComplete=true Aug 30 10:16:09 moojik go-librespot[1242]: time="2026-08-30T10:16:09+05:30" level=trace msg="received dealer pong" Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:16:09 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 10:16:09 moojik volumio[941]: amixer -c 0 info | grep "bcm2835 ALSA" Aug 30 10:16:09 moojik volumio[941]: amixer -c 1 info | grep "bcm2835 Headphones" Aug 30 10:16:09 moojik volumio[941]: Card hw:1 'Headphones'/'bcm2835 Headphones' Aug 30 10:16:09 moojik volumio[941]: amixer -c 5 info | grep "SMSL USB AUDIO" Aug 30 10:16:09 moojik volumio[941]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-3f980000.usb-1.1.2, high speed' Aug 30 10:16:09 moojik volumio[941]: amixer -c 5 info | grep "SMSL USB AUDIO" Aug 30 10:16:09 moojik volumio[941]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-3f980000.usb-1.1.2, high speed' Aug 30 10:16:09 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:09.931+05:30 level=INFO msg="emitting audio outputs changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" selectedOutputId=5 Aug 30 10:16:10 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:10 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:10 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:10 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:10.008+05:30 level=INFO msg="emitting software info changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" currentVersion=3.912 latestVersion=3.912 Aug 30 10:16:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:10.045+05:30 level=INFO msg="emitting software update progress event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" status=UPDATE_STATUS_NONE progress=0 Aug 30 10:16:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:10.105+05:30 level=INFO msg="emitting user changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" userId= Aug 30 10:16:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:10.165+05:30 level=INFO msg="emitting music providers changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" providers=3 Aug 30 10:16:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:10.255+05:30 level=INFO msg="emitting plugins changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" plugins=54 Aug 30 10:16:11 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:11 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:11 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:11.251+05:30 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" state=STATUS_STOPPED positionMs=0 volume=100 Aug 30 10:16:11 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:11.365+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%04 @ 0x1765800" id= title= Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.060+05:30 level=ERROR msg="failed to read message" component=conn/ws remoteAddr=192.168.68.101:43786 error="websocket: close 1001 (going away)" Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.060+05:30 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.68.101:43786 Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.060+05:30 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.68.101:43786 Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.061+05:30 level=ERROR msg="failed to send response" component=server dst=" @ 0x153ab10" id=419462506 status=STATUS_OK error="no addresses to write to" Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.102+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:53132 Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.135+05:30 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.153+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=17.798254ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.255+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://www.googleapis.com duration=118.961222ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.275+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:35848 Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.291+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=http://pushupdates.volumio.org duration=155.398352ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.296+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=160.066418ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.298+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://google.com duration=162.940788ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.300+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=162.745476ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.416+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://securetoken.googleapis.com duration=279.994513ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.422+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=284.733568ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.423+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://functions.volumio.cloud duration=286.260805ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.533+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:52730 Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.681+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://functions.volumio.cloud duration=544.292748ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.762+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=https://database.volumio.cloud duration=625.508873ms Aug 30 10:16:37 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:37.986+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=http://cddb.volumio.org duration=849.64493ms Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.118+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.900734ms timeout=10s endpoint=http://plugins.volumio.org duration=981.724464ms Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.759+05:30 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=62.695423ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.780+05:30 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.798+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=17.603931ms Aug 30 10:16:38 moojik go-librespot[1242]: time="2026-08-30T10:16:38+05:30" level=trace msg="received accesspoint ping" Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.898+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://www.googleapis.com duration=117.371276ms Aug 30 10:16:38 moojik go-librespot[1242]: time="2026-08-30T10:16:38+05:30" level=trace msg="received accesspoint pong ack" Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.935+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=http://pushupdates.volumio.org duration=154.314344ms Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.941+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=159.89121ms Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.943+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=162.537456ms Aug 30 10:16:38 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:38.951+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://google.com duration=169.97635ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.059+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://securetoken.googleapis.com duration=278.249724ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.059+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=278.628786ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.067+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://functions.volumio.cloud duration=285.626848ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.070+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://functions.volumio.cloud duration=288.755489ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.086+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=https://database.volumio.cloud duration=305.141453ms Aug 30 10:16:39 moojik go-librespot[1242]: time="2026-08-30T10:16:39+05:30" level=trace msg="sent dealer ping" Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.266+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=http://cddb.volumio.org duration=485.576016ms Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.322+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:56640 Aug 30 10:16:39 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:39.409+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%01,00:00:00:00:00:00%02,00:00:00:00:00:00%03,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.38823ms timeout=10s endpoint=http://plugins.volumio.org duration=627.481525ms Aug 30 10:16:39 moojik go-librespot[1242]: time="2026-08-30T10:16:39+05:30" level=trace msg="received dealer pong" Aug 30 10:16:43 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:43.687+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:52750 Aug 30 10:16:46 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:46.448+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:16:50 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:50.103+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:16:50 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:50.643+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:56612 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Aug 30 10:16:51 moojik sudo[5947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:16:51 moojik sudo[5947]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:51 moojik sudo[5947]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:51 moojik sudo[5950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:16:51 moojik sudo[5950]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:51 moojik sudo[5950]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:51 moojik sudo[5953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:16:51 moojik sudo[5953]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:51 moojik sudo[5953]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Aug 30 10:16:51 moojik sudo[5955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:16:51 moojik sudo[5955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:51 moojik sudo[5955]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:51 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Aug 30 10:16:52 moojik sudo[5960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:16:52 moojik sudo[5960]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:52 moojik sudo[5960]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:52 moojik sudo[5962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:16:52 moojik sudo[5962]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:52 moojik sudo[5962]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:52 moojik sudo[5966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 10:16:52 moojik sudo[5966]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:52 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Aug 30 10:16:52 moojik sudo[5966]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:52 moojik sudo[5968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 10:16:52 moojik sudo[5968]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:16:52 moojik sudo[5968]: pam_unix(sudo:session): session closed for user root Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 10:16:52 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:52 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: Listing playlists Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:16:52 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111 from 192.168.68.101 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:16:52 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:52.256+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:16:52 moojik volumio[941]: info: FusionDsp - no preset used Aug 30 10:16:52 moojik volumio[941]: info: FusionDsp - .json Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 10:16:52 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:52 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:52 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:52 moojik volumio[941]: info: Listing playlists Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 10:16:52 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:16:52 moojik volumio[941]: info: FusionDsp - no preset used Aug 30 10:16:52 moojik volumio[941]: info: FusionDsp - .json Aug 30 10:16:53 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:53.402+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:46852 Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:53 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:53 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:53 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:53 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:54 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:54 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:54 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 10:16:54 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 10:16:55 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:55 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:55 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:55 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:55 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:55 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:55 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:55 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:56 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:56 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:56 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:56 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:56 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:56 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:56 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:56 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:57 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:57.495+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:46862 Aug 30 10:16:57 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:57 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:57 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:57 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:57 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:57 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:57 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:57 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:58 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:58 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:58 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:16:58 moojik volumio[941]: info: Received Get System Info Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:16:58 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:16:58 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:16:58 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:16:59 moojik volumio5-onboarding[1214]: time=2026-08-30T10:16:59.521+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.400+05:30 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.420+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=20.192468ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.521+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://www.googleapis.com duration=120.173042ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.558+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=http://pushupdates.volumio.org duration=156.420642ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.561+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=159.774126ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.564+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=163.720891ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.567+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://google.com duration=166.170314ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.679+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=278.164618ms Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:17:01 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:17:01 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.692+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://functions.volumio.cloud duration=290.903036ms Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.706+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://database.volumio.cloud duration=304.310151ms Aug 30 10:17:01 moojik volumio[941]: verbose: New Socket.io Connection to 192.168.68.111:3000 from 192.168.68.101 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 18 Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 30 10:17:01 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 30 10:17:01 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:01.950+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://functions.volumio.cloud duration=549.520394ms Aug 30 10:17:02 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 10:17:02 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 30 10:17:02 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:02.254+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=http://cddb.volumio.org duration=852.644661ms Aug 30 10:17:02 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:02.332+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=https://securetoken.googleapis.com duration=930.572977ms Aug 30 10:17:02 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:02.419+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.68.101:53132,00:00:00:00:00:00%04 @ 0x19a5650" latency=48.864029ms timeout=10s endpoint=http://plugins.volumio.org duration=1.017415967s Aug 30 10:17:03 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:03.597+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:03 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:03.897+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:04 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 10:17:04 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 10:17:04 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:04 moojik volumio[941]: info: FusionDsp - no preset used Aug 30 10:17:04 moojik volumio[941]: info: FusionDsp - .json Aug 30 10:17:05 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:05.688+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.101:51230 Aug 30 10:17:05 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:05.702+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:09 moojik go-librespot[1242]: time="2026-08-30T10:17:09+05:30" level=trace msg="sent dealer ping" Aug 30 10:17:09 moojik go-librespot[1242]: time="2026-08-30T10:17:09+05:30" level=trace msg="received dealer pong" Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 10:17:10 moojik volumio[941]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 30 10:17:10 moojik volumio[941]: info: Received Get System Version Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 10:17:10 moojik volumio[941]: info: Received Get System Info Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 10:17:10 moojik volumio[941]: info: Discovery: Getting this device information Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::volumioGetState Aug 30 10:17:10 moojik volumio[941]: info: CorePlayQueue::getTrack 0 Aug 30 10:17:10 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 10:17:10 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:10.865+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: info: Retrieving Cloud Streaming UI Aug 30 10:17:11 moojik volumio[941]: info: Getting Tidal Cloud Configuration Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: info: Getting Qobuz Cloud Configuration Aug 30 10:17:11 moojik volumio[941]: info: Asking plugin for UI Config Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: info: Getting Spotify Cloud Configuration Aug 30 10:17:11 moojik volumio[941]: info: Asking plugin for UI Config Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: info: Saving Spotify Acccount Aug 30 10:17:11 moojik volumio[941]: info: Got Tidal Cloud Configuration Aug 30 10:17:11 moojik volumio[941]: info: Got it Aug 30 10:17:11 moojik volumio[941]: info: Got it Aug 30 10:17:11 moojik volumio[941]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:17:11 moojik volumio[941]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::volumioGetBrowseSources Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 10:17:11 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 30 10:17:14 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:14.822+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:14 moojik volumio[941]: info: Disabling MyMusic plugin upnp Aug 30 10:17:14 moojik sudo[6038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 30 10:17:14 moojik sudo[6038]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 30 10:17:14 moojik systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 30 10:17:14 moojik volumio[941]: error: Upnp client error: Error: This socket has been ended by the other party Aug 30 10:17:15 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:15.122+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:15 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 10:17:19 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:19.083+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:19 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 10:17:21 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:21.866+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:23 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 30 10:17:24 moojik volumio[941]: info: Enabling MyMusic plugin upnp Aug 30 10:17:24 moojik volumio[941]: info: Enabling plugin upnp Aug 30 10:17:24 moojik volumio[941]: info: Loading plugin "upnp"... Aug 30 10:17:24 moojik volumio[941]: info: [1788065244980] Starting Upmpd Daemon Aug 30 10:17:24 moojik volumio[941]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 30 10:17:24 moojik volumio[941]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:17:24 moojik volumio[941]: Error: listen EADDRINUSE: address already in use :::6599 Aug 30 10:17:24 moojik volumio[941]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 30 10:17:24 moojik volumio[941]: at listenInCluster (net.js:1366:12) Aug 30 10:17:24 moojik volumio[941]: at Server.listen (net.js:1452:7) Aug 30 10:17:24 moojik volumio[941]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 30 10:17:24 moojik volumio[941]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 30 10:17:24 moojik volumio[941]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 30 10:17:24 moojik volumio[941]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 30 10:17:24 moojik volumio[941]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 30 10:17:24 moojik volumio[941]: code: 'EADDRINUSE', Aug 30 10:17:24 moojik volumio[941]: errno: -98, Aug 30 10:17:24 moojik volumio[941]: syscall: 'listen', Aug 30 10:17:24 moojik volumio[941]: address: '::', Aug 30 10:17:24 moojik volumio[941]: port: 6599 Aug 30 10:17:24 moojik volumio[941]: } Aug 30 10:17:25 moojik volumio[941]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:17:25 moojik volumio5-onboarding[1214]: time=2026-08-30T10:17:25.176+05:30 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Aug 30 10:17:25 moojik sudo[6064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-30 10:16 Aug 30 10:17:25 moojik sudo[6064]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"