-- Logs begin at Sat 2026-10-10 14:37:21 UTC, end at Fri 2026-10-16 18:42:03 UTC. -- Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.541Z level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.73:35824 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.556Z level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519812584s platform=PLATFORM_ANDROID version=6.261014.0 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.561Z level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.515538055s timeout=20s Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.561Z level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" Oct 16 18:41:12 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:12 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:12 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.569Z level=INFO msg="emitting device name changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" name=Volumio Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.570Z level=INFO msg="emitting device language changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" language=ru Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.571Z level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" timezone=UTC Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.572Z level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" available=true connected=true macAddress=3c:7c:3f:cd:aa:5e ip4Address=192.168.1.135/24 ip6Address= Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.573Z level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.573Z level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" setupComplete=true Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 16 18:41:12 volumio volumio[824]: amixer -c 0 info | grep "Analog RCA Output" Oct 16 18:41:12 volumio volumio[824]: amixer -c 5 info | grep "DENAFRIPS USB Audio V3.12 I1.6" Oct 16 18:41:12 volumio volumio[824]: Card hw:5 'I16'/'DENAFRIPS DENAFRIPS USB Audio V3.12 I1.6 at usb-ff540000.usb-1.2, high speed' Oct 16 18:41:12 volumio volumio[824]: amixer -c 5 info | grep "DENAFRIPS USB Audio V3.12 I1.6" Oct 16 18:41:12 volumio volumio[824]: Card hw:5 'I16'/'DENAFRIPS DENAFRIPS USB Audio V3.12 I1.6 at usb-ff540000.usb-1.2, high speed' Oct 16 18:41:12 volumio volumio[824]: Invalid card number. Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.924Z level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" selectedOutputId=5 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:12 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:12 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:12 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135:3000 from 192.168.1.73 UA: Dart/3.13 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 18:41:12 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:12 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:12 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.997Z level=INFO msg="emitting software info changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" currentVersion=3.912 latestVersion=3.912 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.998Z level=INFO msg="emitting software update progress event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" status=UPDATE_STATUS_NONE progress=0 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.998Z level=INFO msg="emitting user changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" userId=dCUE576m7dUjApC5TPoJl4GhwGj1 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.998Z level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" providers=9 Oct 16 18:41:12 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:12.998Z level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" plugins=54 Oct 16 18:41:12 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:12 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:13 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:13.001Z level=INFO msg="emitting player state changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" state=STATUS_STOPPED positionMs=1250 volume=100 Oct 16 18:41:13 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:13.001Z level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.73:35824 @ 0x1aa00f0" id="mnt/NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/01 - Trouble’s What You’re In.flac" title="1 - Trouble’s What You’re In" Oct 16 18:41:14 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:14.890Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518335122s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 18:41:14 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:14.923Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.318Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://www.googleapis.com duration=393.456976ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.376Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=http://pushupdates.volumio.org duration=451.615626ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.440Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=516.949898ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.467Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://securetoken.googleapis.com duration=542.335537ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.559Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://google.com duration=634.638331ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.613Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=687.895788ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.643Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=718.691872ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.732Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://functions.volumio.cloud duration=808.102484ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.741Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://functions.volumio.cloud duration=816.45353ms Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.788Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=863.338327ms Oct 16 18:41:15 volumio sudo[32295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:15 volumio sudo[32295]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:15 volumio sudo[32298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:15 volumio sudo[32295]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:15 volumio sudo[32298]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:15 volumio sudo[32298]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.860Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=https://database.volumio.cloud duration=936.291556ms Oct 16 18:41:15 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.956Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=http://plugins.volumio.org duration=1.031397867s Oct 16 18:41:15 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:15.977Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.502445776s timeout=10s endpoint=http://cddb.volumio.org duration=1.052759649s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:41:16 volumio sudo[32316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:16 volumio sudo[32316]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:16 volumio sudo[32319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:16 volumio sudo[32316]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:16 volumio sudo[32319]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:16 volumio sudo[32319]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:16 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 18:41:16 volumio volumio[824]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 18:41:16 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:16 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:16 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:16 volumio volumio[824]: info: Listing playlists Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 18:41:16 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:18 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:18 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:18 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:18 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:19 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:19 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:19 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:19 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:19 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:19 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:19 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:19 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.635Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:20 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:20 volumio volumio[824]: info: CorePlayQueue::getTrack 0 Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:20 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135:3000 from 192.168.1.73 UA: Dart/3.13 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 16 18:41:20 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.726Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=91.405915ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.738Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=http://pushupdates.volumio.org duration=101.314177ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.743Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=106.945124ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.757Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=121.516578ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.783Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://google.com duration=147.971764ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.802Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://www.googleapis.com duration=166.127238ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.832Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=195.870692ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.840Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://securetoken.googleapis.com duration=204.957908ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.885Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://database.volumio.cloud duration=248.164476ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.891Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://functions.volumio.cloud duration=255.226348ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.899Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=https://functions.volumio.cloud duration=262.997557ms Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.964Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=http://cddb.volumio.org duration=328.050078ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:41:20 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:20.986Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.509918967s timeout=10s endpoint=http://plugins.volumio.org duration=349.471069ms Oct 16 18:41:23 volumio volumio[824]: info: CoreCommandRouter::volumioGetQueue Oct 16 18:41:23 volumio volumio[824]: info: CoreStateMachine::getQueue Oct 16 18:41:23 volumio volumio[824]: info: CorePlayQueue::getQueue Oct 16 18:41:26 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::play index 5 Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::stop Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:41:26 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:26 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:41:26 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:26 volumio volumio[824]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/06 - Under The Same Stars.flac Oct 16 18:41:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:41:26 volumio volumio[824]: info: sendMpdCommand stop took 2 milliseconds Oct 16 18:41:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand clear Oct 16 18:41:26 volumio volumio[824]: info: Oct 16 18:41:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 16 18:41:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 16 18:41:26 volumio volumio[824]: info: sendMpdCommand clear took 1 milliseconds Oct 16 18:41:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand add "NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/06 - Under The Same Stars.flac" Oct 16 18:41:26 volumio volumio[824]: error: updateQueue error: null Oct 16 18:41:26 volumio volumio[824]: info: Oct 16 18:41:26 volumio volumio[824]: ---------------------------- MPD announces system playlist update Oct 16 18:41:26 volumio volumio[824]: info: Ignoring MPD Status Update Oct 16 18:41:26 volumio volumio[824]: info: ------------------------------ 2ms Oct 16 18:41:26 volumio volumio[824]: info: sendMpdCommand add "NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/06 - Under The Same Stars.flac" took 2 milliseconds Oct 16 18:41:26 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand play Oct 16 18:41:26 volumio volumio[824]: info: ------------------------------ 2ms Oct 16 18:41:26 volumio volumio[824]: info: sendMpdCommand play took 1 milliseconds Oct 16 18:41:26 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 18:41:32 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:41:32 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:41:32 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:32 volumio volumio[824]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/06 - Under The Same Stars.flac Oct 16 18:41:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:41:32 volumio volumio[824]: info: CoreCommandRouter::volumioPlay Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::play index undefined Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 16 18:41:32 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:32 volumio volumio[824]: info: CoreStateMachine::startPlaybackTimer Oct 16 18:41:32 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:32 volumio volumio[824]: verbose: ControllerMpd::clearAddPlayTracks NAS/Synology/Rock/Fink/Distance and Time/FLAC (16bit-44.1kHz)/06 - Under The Same Stars.flac Oct 16 18:41:32 volumio volumio[824]: verbose: ControllerMpd::sendMpdCommand stop Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.349Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.515092091s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.377Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.470Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=92.939215ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.479Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=http://pushupdates.volumio.org duration=101.049051ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.499Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=121.271285ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.504Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=125.955477ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.523Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://google.com duration=145.242332ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.544Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://www.googleapis.com duration=165.973237ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.566Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://securetoken.googleapis.com duration=188.499068ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.574Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=196.41757ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.614Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://database.volumio.cloud duration=236.234953ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.621Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://functions.volumio.cloud duration=243.850411ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.668Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=https://functions.volumio.cloud duration=290.045413ms Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.691Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=http://cddb.volumio.org duration=312.931163ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:41:44 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:44.711Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.519290022s timeout=10s endpoint=http://plugins.volumio.org duration=333.183731ms Oct 16 18:41:44 volumio sudo[32423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:44 volumio sudo[32423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:45 volumio sudo[32426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:45 volumio sudo[32423]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:45 volumio sudo[32426]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:45 volumio sudo[32426]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:45 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 16 18:41:45 volumio sudo[32443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:45 volumio sudo[32443]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:45 volumio sudo[32446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:45 volumio sudo[32443]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:45 volumio sudo[32446]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:45 volumio sudo[32446]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:45 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:45 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 18:41:45 volumio volumio[824]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 18:41:45 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:45 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:45 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:45 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:45 volumio volumio[824]: info: Listing playlists Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 18:41:45 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:47 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:47 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:47 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:47 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:48 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:48 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:48 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:48 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:48 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:48 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:48 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:48 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:49 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:49.882Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518342467s timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 16 18:41:49 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:49.914Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.013Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=99.357957ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.015Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=http://pushupdates.volumio.org duration=100.599006ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.024Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=109.454637ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.058Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=144.420412ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.062Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://google.com duration=148.134515ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.081Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://www.googleapis.com duration=167.149827ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.113Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://myvolumio.firebaseio.com duration=198.802249ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.114Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://securetoken.googleapis.com duration=200.278382ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.170Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://functions.volumio.cloud duration=255.683684ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.182Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://functions.volumio.cloud duration=267.742124ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.186Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=https://database.volumio.cloud duration=272.00019ms Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.209Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=http://cddb.volumio.org duration=295.128024ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 16 18:41:50 volumio volumio5-onboarding[1073]: time=2026-10-16T18:41:50.260Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.73:35824 @ 0x1aa00f0" latency=-3h58m57.518992545s timeout=10s endpoint=http://plugins.volumio.org duration=346.183385ms Oct 16 18:41:50 volumio sudo[32463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:50 volumio sudo[32463]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:50 volumio sudo[32466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:50 volumio sudo[32463]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:50 volumio sudo[32466]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:50 volumio sudo[32466]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:50 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 16 18:41:50 volumio sudo[32483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 16 18:41:50 volumio sudo[32483]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:50 volumio sudo[32486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 16 18:41:50 volumio sudo[32483]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:50 volumio sudo[32486]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:41:50 volumio sudo[32486]: pam_unix(sudo:session): session closed for user root Oct 16 18:41:50 volumio volumio[824]: verbose: New Socket.io Connection to 192.168.1.135 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; DN2103 Build/TP1A.220905.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.261014.0 Engine version: 3 Transport: polling Total Clients: 10 Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::volumioGetVisibleSources Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:51 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 16 18:41:51 volumio volumio[824]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 16 18:41:51 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:51 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:51 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:51 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:51 volumio volumio[824]: info: Listing playlists Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 16 18:41:51 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:52 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:52 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:52 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:52 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 16 18:41:54 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 16 18:41:54 volumio volumio[824]: info: Received Get System Info Oct 16 18:41:54 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 18:41:54 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 18:41:54 volumio volumio[824]: info: Discovery: Getting this device information Oct 16 18:41:54 volumio volumio[824]: info: CoreCommandRouter::volumioGetState Oct 16 18:41:54 volumio volumio[824]: info: CorePlayQueue::getTrack 5 Oct 16 18:41:54 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 18:41:55 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: Retrieving Cloud Streaming UI Oct 16 18:41:56 volumio volumio[824]: info: Getting Tidal Cloud Configuration Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: Getting Qobuz Cloud Configuration Oct 16 18:41:56 volumio volumio[824]: info: Asking plugin for UI Config Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: Getting Spotify Cloud Configuration Oct 16 18:41:56 volumio volumio[824]: info: Asking plugin for UI Config Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: Saving Spotify Acccount Oct 16 18:41:56 volumio volumio[824]: info: Got it Oct 16 18:41:56 volumio volumio[824]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 16 18:41:56 volumio volumio[824]: info: Got Tidal Cloud Configuration Oct 16 18:41:56 volumio volumio[824]: info: Got it Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:41:56 volumio volumio[824]: info: CoreCommandRouter::volumioGetBrowseSources Oct 16 18:41:57 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:41:57 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 16 18:42:00 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 16 18:42:01 volumio volumio[824]: info: Disabling MyMusic plugin upnp Oct 16 18:42:01 volumio sudo[32520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 16 18:42:01 volumio sudo[32520]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 18:42:01 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 16 18:42:01 volumio volumio[824]: error: Upnp client error: Error: This socket has been ended by the other party Oct 16 18:42:01 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 16 18:42:02 volumio volumio[824]: info: Enabling MyMusic plugin upnp Oct 16 18:42:02 volumio volumio[824]: info: Enabling plugin upnp Oct 16 18:42:02 volumio volumio[824]: info: Loading plugin "upnp"... Oct 16 18:42:02 volumio volumio[824]: info: [1792176122724] Starting Upmpd Daemon Oct 16 18:42:02 volumio volumio[824]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 16 18:42:02 volumio volumio[824]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:42:02 volumio volumio[824]: Error: listen EADDRINUSE: address already in use :::6599 Oct 16 18:42:02 volumio volumio[824]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 16 18:42:02 volumio volumio[824]: at listenInCluster (net.js:1379:12) Oct 16 18:42:02 volumio volumio[824]: at Server.listen (net.js:1465:7) Oct 16 18:42:02 volumio volumio[824]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 16 18:42:02 volumio volumio[824]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 16 18:42:02 volumio volumio[824]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 16 18:42:02 volumio volumio[824]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 16 18:42:02 volumio volumio[824]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 16 18:42:02 volumio volumio[824]: code: 'EADDRINUSE', Oct 16 18:42:02 volumio volumio[824]: errno: -98, Oct 16 18:42:02 volumio volumio[824]: syscall: 'listen', Oct 16 18:42:02 volumio volumio[824]: address: '::', Oct 16 18:42:02 volumio volumio[824]: port: 6599 Oct 16 18:42:02 volumio volumio[824]: } Oct 16 18:42:02 volumio volumio[824]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 18:42:03 volumio sudo[32550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-16 18:41 Oct 16 18:42:03 volumio sudo[32550]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="3dada8b1e619a5feb94867e0865ace17474d7bce" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="30045b259d1a704a832ca7c1460c1fdfa4b723f9" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Mar 2026 12:25:22 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="cf5455596c5328b0cbc691c04ff0184a"