Oct 15 17:27:05 volumio go-librespot[1574]: time="2026-10-15T17:27:05+08:00" level=debug msg="fetched chunk 7/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:27:17 volumio go-librespot[1574]: time="2026-10-15T17:27:17+08:00" level=debug msg="fetched chunk 8/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:27:28 volumio go-librespot[1574]: time="2026-10-15T17:27:28+08:00" level=debug msg="fetched chunk 9/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:27:41 volumio go-librespot[1574]: time="2026-10-15T17:27:41+08:00" level=debug msg="fetched chunk 10/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.364+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.512+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=148.403015ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.573+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=209.225902ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.629+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://www.googleapis.com duration=264.275705ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.671+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=306.031257ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.727+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://google.com duration=360.857231ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.788+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://securetoken.googleapis.com duration=423.985208ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.807+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=http://pushupdates.volumio.org duration=440.921654ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.824+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://functions.volumio.cloud duration=458.116041ms Oct 15 17:27:41 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:41.946+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=http://plugins.volumio.org duration=579.518154ms Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:27:42 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:42 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:27:42 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:42.036+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://functions.volumio.cloud duration=671.416538ms Oct 15 17:27:42 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.101.165:3000 from 192.168.101.17 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 17:27:42 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:42.103+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=http://cddb.volumio.org duration=738.231952ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 17:27:42 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:42.144+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=778.029371ms Oct 15 17:27:42 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:42.191+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=428.998112ms timeout=10s endpoint=https://database.volumio.cloud duration=824.814689ms Oct 15 17:27:42 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.101.165 from 192.168.101.17 UA: Mozilla/5.0 (Linux; Android 13; NTH-NX9 Build/HONORNTH-N29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.107 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:42 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 17:27:42 volumio volumio[1229]: info: Received Get System Info Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:27:42 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:42 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:42 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:42 volumio volumio[1229]: info: Listing playlists Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::volumioGetQueue Oct 15 17:27:42 volumio volumio[1229]: info: CoreStateMachine::getQueue Oct 15 17:27:42 volumio volumio[1229]: info: CorePlayQueue::getQueue Oct 15 17:27:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.582+08:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=440.316936ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.623+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.637+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=14.216494ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.638+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=14.211809ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.641+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=17.081852ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.666+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://google.com duration=42.658056ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.733+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://www.googleapis.com duration=109.167697ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.798+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=http://pushupdates.volumio.org duration=174.142884ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.855+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://functions.volumio.cloud duration=230.637866ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.855+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://functions.volumio.cloud duration=230.843159ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.860+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=236.455247ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.864+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://database.volumio.cloud duration=239.88452ms Oct 15 17:27:47 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:47.869+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=https://securetoken.googleapis.com duration=245.65642ms Oct 15 17:27:48 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:48.051+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=http://plugins.volumio.org duration=426.923606ms Oct 15 17:27:48 volumio volumio5-onboarding[1497]: time=2026-10-15T17:27:48.243+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=430.555732ms timeout=10s endpoint=http://cddb.volumio.org duration=619.557985ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 17:27:48 volumio sudo[4346]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:27:48 volumio sudo[4346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:27:48 volumio sudo[4346]: pam_unix(sudo:session): session closed for user root Oct 15 17:27:48 volumio sudo[4348]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:27:48 volumio sudo[4348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:27:48 volumio sudo[4348]: pam_unix(sudo:session): session closed for user root Oct 15 17:27:48 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.101.165 from 192.168.101.17 UA: Mozilla/5.0 (Linux; Android 13; NTH-NX9 Build/HONORNTH-N29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.107 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 15 17:27:48 volumio sudo[4352]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 17:27:48 volumio sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:27:48 volumio sudo[4352]: pam_unix(sudo:session): session closed for user root Oct 15 17:27:48 volumio sudo[4354]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 17:27:48 volumio sudo[4354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 17:27:48 volumio sudo[4354]: pam_unix(sudo:session): session closed for user root Oct 15 17:27:48 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.101.165 from 192.168.101.17 UA: Mozilla/5.0 (Linux; Android 13; NTH-NX9 Build/HONORNTH-N29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.107 Mobile Safari/537.36 VolumioApp/6.260915.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:49 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 17:27:49 volumio volumio[1229]: info: Received Get System Info Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:27:49 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:49 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:49 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:49 volumio volumio[1229]: info: Listing playlists Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 17:27:49 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:27:50 volumio volumio[1229]: info: Received Get System Info Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:27:50 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:50 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:27:50 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 15 17:27:51 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 17:27:51 volumio volumio[1229]: info: Received Get System Info Oct 15 17:27:51 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:27:51 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:27:51 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:27:51 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:27:51 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:27:51 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:27:53 volumio volumio[1229]: info: CoreCommandRouter::volumioGetQueue Oct 15 17:27:53 volumio volumio[1229]: info: CoreStateMachine::getQueue Oct 15 17:27:53 volumio volumio[1229]: info: CorePlayQueue::getQueue Oct 15 17:27:54 volumio go-librespot[1574]: time="2026-10-15T17:27:54+08:00" level=debug msg="fetched chunk 11/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:27:58 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 15 17:28:07 volumio go-librespot[1574]: time="2026-10-15T17:28:07+08:00" level=debug msg="fetched chunk 12/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 17:28:07 volumio volumio[1229]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 15 17:28:07 volumio volumio[1229]: info: Received Get System Version Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 15 17:28:07 volumio volumio[1229]: info: Received Get System Info Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:28:07 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:28:07 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:28:07 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:28:21 volumio go-librespot[1574]: time="2026-10-15T17:28:21+08:00" level=debug msg="fetched chunk 13/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:28:36 volumio go-librespot[1574]: time="2026-10-15T17:28:36+08:00" level=debug msg="fetched chunk 14/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.377+08:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.387+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=9.514725ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.392+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=14.612785ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.394+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=17.117612ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.418+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://google.com duration=39.99481ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.487+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://www.googleapis.com duration=109.426444ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.552+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=http://pushupdates.volumio.org duration=174.010885ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.613+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://functions.volumio.cloud duration=234.717355ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.615+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=237.234423ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.619+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://securetoken.googleapis.com duration=241.578218ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.627+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://database.volumio.cloud duration=248.401156ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.662+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=http://plugins.volumio.org duration=283.886173ms Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.695+08:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=https://functions.volumio.cloud duration=316.783272ms Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 17:28:37 volumio volumio[1229]: info: Discovery: Getting this device information Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::volumioGetState Oct 15 17:28:37 volumio volumio[1229]: info: CorePlayQueue::getTrack 7 Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 17:28:37 volumio volumio[1229]: verbose: New Socket.io Connection to 192.168.101.165:3000 from 192.168.101.17 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 15 17:28:37 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 15 17:28:37 volumio volumio5-onboarding[1497]: time=2026-10-15T17:28:37.796+08:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.101.17:43354,00:00:00:00:00:00%01 @ 0x1c08750" latency=451.510425ms timeout=10s endpoint=http://cddb.volumio.org duration=418.011765ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 15 17:28:42 volumio volumio[1229]: Searching plugin music_service/spop Oct 15 17:28:42 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 15 17:28:42 volumio volumio[1229]: info: All search sources collected, pushing search results Oct 15 17:28:47 volumio volumio[1229]: Searching plugin music_service/spop Oct 15 17:28:47 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 15 17:28:47 volumio volumio[1229]: info: All search sources collected, pushing search results Oct 15 17:28:49 volumio go-librespot[1574]: time="2026-10-15T17:28:49+08:00" level=debug msg="fetched chunk 15/20, size: 524288" uri="spotify:track:6UOzYUDpm10FlZF9P8BzWb" Oct 15 17:28:52 volumio volumio[1229]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Oct 15 17:28:52 volumio volumio[1229]: info: In handleBrowseUri, curUri=spotify:artist:0E1GyILsvYfm7WbOM8zntX Oct 15 17:28:53 volumio volumio[1229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:28:53 volumio volumio[1229]: TypeError: Cannot read properties of undefined (reading 'url') Oct 15 17:28:53 volumio volumio[1229]: at /data/plugins/music_service/spop/index.js:3360:60 Oct 15 17:28:53 volumio volumio[1229]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Oct 15 17:28:53 volumio volumio[1229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 17:28:53 volumio sudo[4467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 17:27' Oct 15 17:28:53 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"