Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.402Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.712Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=http://pushupdates.volumio.org duration=307.006362ms Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.794Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=391.412203ms Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.912Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://www.googleapis.com duration=507.045505ms Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.925Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://securetoken.googleapis.com duration=519.933881ms Oct 14 07:04:25 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:25.992Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=584.523429ms Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.032Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=628.610849ms Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.169Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://functions.volumio.cloud duration=760.667694ms Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.171Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://functions.volumio.cloud duration=763.681152ms Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.195Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=787.223822ms Oct 14 07:04:26 rivoplus volumio[3252]: verbose: New Socket.io Connection to 192.168.0.9 from 192.168.0.10 UA: Mozilla/5.0 (Linux; Android 16; SM-S938B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/154.0.8037.106 Mobile Safari/537.36 VolumioApp/6.260930.0 Engine version: 3 Transport: polling Total Clients: 9 Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetState Oct 14 07:04:26 rivoplus volumio[3252]: info: CorePlayQueue::getTrack 4 Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetQueue Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreStateMachine::getQueue Oct 14 07:04:26 rivoplus volumio[3252]: info: CorePlayQueue::getQueue Oct 14 07:04:26 rivoplus volumio[3252]: info: Listing playlists Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.382Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://database.volumio.cloud duration=975.319547ms Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 07:04:26 rivoplus volumio[3252]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 14 07:04:26 rivoplus volumio[3252]: info: Received Get System Info Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 07:04:26 rivoplus volumio[3252]: info: Discovery: Getting this device information Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetState Oct 14 07:04:26 rivoplus volumio[3252]: info: CorePlayQueue::getTrack 4 Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetState Oct 14 07:04:26 rivoplus volumio[3252]: info: CorePlayQueue::getTrack 4 Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.410Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=https://google.com duration=1.003027424s Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.483Z level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=http://cddb.volumio.org duration=1.07525364s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 14 07:04:26 rivoplus volumio[3252]: info: Discovery: Getting this device information Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::volumioGetState Oct 14 07:04:26 rivoplus volumio[3252]: info: CorePlayQueue::getTrack 4 Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 14 07:04:26 rivoplus volumio[3252]: verbose: New Socket.io Connection to 192.168.0.9:3000 from 192.168.0.10 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 14 07:04:26 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 14 07:04:26 rivoplus volumio5-onboarding[3824]: time=2026-10-14T07:04:26.703Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.10:46816,00:00:00:00:00:00%01 @ 0x251ec30" latency=50.365834ms timeout=10s endpoint=http://plugins.volumio.org duration=1.297937785s Oct 14 07:04:37 rivoplus volumio[3252]: Searching all installed plugins Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: , search Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: mpd , search Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Oct 14 07:04:37 rivoplus volumio[3252]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: webradio , search Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: tidal , search Oct 14 07:04:37 rivoplus volumio[3252]: info: Refreshing TIDAL token Oct 14 07:04:37 rivoplus volumio[3252]: info: CoreCommandRouter::executeOnPlugin: spop , search Oct 14 07:04:37 rivoplus volumio[3252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 07:04:37 rivoplus volumio[3252]: XMLStructuredError: Premature end of data in tag br line 187 (Line: 195, Column: 7) Oct 14 07:04:37 rivoplus volumio[3252]: at new XMLStructuredError (/volumio/node_modules/libxmljs/dist/lib/types.js:23:28) Oct 14 07:04:37 rivoplus volumio[3252]: at /volumio/node_modules/libxmljs/dist/lib/parse.js:190:23 Oct 14 07:04:37 rivoplus volumio[3252]: at Object.parseXml (/volumio/node_modules/libxmljs/dist/lib/parse.js:185:49) Oct 14 07:04:37 rivoplus volumio[3252]: at /volumio/app/plugins/music_service/webradio/index.js:962:31 Oct 14 07:04:37 rivoplus volumio[3252]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) { Oct 14 07:04:37 rivoplus volumio[3252]: domain: 1, Oct 14 07:04:37 rivoplus volumio[3252]: code: 77, Oct 14 07:04:37 rivoplus volumio[3252]: level: 3, Oct 14 07:04:37 rivoplus volumio[3252]: column: 7, Oct 14 07:04:37 rivoplus volumio[3252]: file: '', Oct 14 07:04:37 rivoplus volumio[3252]: line: 195, Oct 14 07:04:37 rivoplus volumio[3252]: str1: 'br', Oct 14 07:04:37 rivoplus volumio[3252]: str2: undefined, Oct 14 07:04:37 rivoplus volumio[3252]: str3: undefined, Oct 14 07:04:37 rivoplus volumio[3252]: int1: 187 Oct 14 07:04:37 rivoplus volumio[3252]: } Oct 14 07:04:37 rivoplus volumio[3252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 07:04:38 rivoplus sudo[13928]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 07:03' Oct 14 07:04:38 rivoplus sudo[13928]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="8ed05bf20f96eb8557ed5652c420f84f39d66325" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:52:40 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo Plus" VOLUMIO_HASH="ba37532a86808e19439a6278b2d45f47"