Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.113Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.337Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=223.970371ms Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.677Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=http://pushupdates.volumio.org duration=561.811444ms Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.766Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=650.465065ms Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.769Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=653.214406ms Oct 13 08:59:20 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:20.878Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=http://cddb.volumio.org duration=764.084307ms error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 13 08:59:21 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:21.293Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.177276274s Oct 13 08:59:21 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:21.293Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://functions.volumio.cloud duration=1.178679445s Oct 13 08:59:21 primo-plus volumio[8123]: verbose: New Socket.io Connection to 192.168.1.61 from 192.168.1.32 UA: Mozilla/5.0 (iPad; CPU OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetVisibleSources Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetState Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetState Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 13 08:59:21 primo-plus volumio[8123]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: inputs , handleBrowseUri Oct 13 08:59:21 primo-plus volumio[8123]: info: Received Get System Info Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 08:59:21 primo-plus volumio[8123]: info: Discovery: Getting this device information Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetState Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetState Oct 13 08:59:21 primo-plus volumio[8123]: info: Listing playlists Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetQueue Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreStateMachine::getQueue Oct 13 08:59:21 primo-plus volumio[8123]: info: CorePlayQueue::getQueue Oct 13 08:59:21 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 13 08:59:21 primo-plus bluealsa[878]: bluez.c:1290: Signal: org.freedesktop.DBus.ObjectManager.InterfacesAdded(/org/bluez/hci0/dev_69_EA_50_00_98_5B, ...) Oct 13 08:59:21 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:21.971Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://database.volumio.cloud duration=1.855582152s Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 13 08:59:22 primo-plus volumio[8123]: info: Discovery: Getting this device information Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::volumioGetState Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 13 08:59:22 primo-plus volumio[8123]: verbose: New Socket.io Connection to 192.168.1.61:3000 from 192.168.1.32 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 13 08:59:22 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 13 08:59:26 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 13 08:59:27 primo-plus volumio[8123]: info: browseTIDALUri took 965 milliseconds Oct 13 08:59:27 primo-plus volumio[8123]: info: Preload queue cleared Oct 13 08:59:27 primo-plus volumio[8123]: info: Preload queue cleared Oct 13 08:59:28 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:28.513Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=8.397882327s Oct 13 08:59:30 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:30.114Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://www.googleapis.com duration=9.99928234s error="Head \"https://www.googleapis.com\": context deadline exceeded" Oct 13 08:59:30 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:30.114Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://securetoken.googleapis.com duration=10.000883138s error="Head \"https://securetoken.googleapis.com\": context deadline exceeded" Oct 13 08:59:30 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:30.114Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=https://google.com duration=10.000193201s error="Head \"https://google.com\": context deadline exceeded" Oct 13 08:59:30 primo-plus volumio5-onboarding[2112]: time=2026-10-13T08:59:30.114Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.32:50025 @ 0x30ae3f0" latency=126.917044ms timeout=10s endpoint=http://plugins.volumio.org duration=9.997568395s error="Head \"http://plugins.volumio.workers.dev/\": context deadline exceeded" Oct 13 08:59:32 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 13 08:59:33 primo-plus volumio[8123]: info: browseTIDALUri took 913 milliseconds Oct 13 08:59:33 primo-plus volumio[8123]: info: Preload queue cleared Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009128 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009129 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009130 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009131 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009132 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009133 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009134 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009135 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009136 Oct 13 08:59:33 primo-plus volumio[8123]: info: Preloading song: tidal://song/16009137 Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009128 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009129 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Executing endpoint getSimilarAlbums Oct 13 08:59:33 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009130 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Executing endpoint metavolumio Oct 13 08:59:33 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009131 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Executing endpoint metavolumio Oct 13 08:59:33 primo-plus volumio[8123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009132 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009133 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009134 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: explodeTIDALUri took 307 milliseconds Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009135 in service tidal Oct 13 08:59:33 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009136 in service tidal Oct 13 08:59:34 primo-plus volumio[8123]: info: Exploding uri tidal://song/16009137 in service tidal Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 303 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 834 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 787 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 854 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 673 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 959 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 888 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 830 milliseconds Oct 13 08:59:34 primo-plus volumio[8123]: info: explodeTIDALUri took 756 milliseconds Oct 13 08:59:35 primo-plus volumio[8123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:59:35 primo-plus volumio[8123]: Error: connect ECONNREFUSED 192.133.77.191:443 Oct 13 08:59:35 primo-plus volumio[8123]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 13 08:59:35 primo-plus volumio[8123]: errno: -111, Oct 13 08:59:35 primo-plus volumio[8123]: code: 'ECONNREFUSED', Oct 13 08:59:35 primo-plus volumio[8123]: syscall: 'connect', Oct 13 08:59:35 primo-plus volumio[8123]: address: '192.133.77.191', Oct 13 08:59:35 primo-plus volumio[8123]: port: 443 Oct 13 08:59:35 primo-plus volumio[8123]: } Oct 13 08:59:35 primo-plus volumio[8123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:59:36 primo-plus sudo[27541]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-13 08:58' Oct 13 08:59:36 primo-plus sudo[27541]: 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="ceea798be624bcca033d94ae449c2a749a9724f0" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="9938d7179e3b7c4e41f3e2d60c255985cff08fee" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 27 09:37:15 UTC 2026" VOLUMIO_VERSION="4.164" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="c8e7083e83ff605518b1cfc23784b0e7"