Oct 02 18:36:55 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:55.977-07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.216-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=238.311765ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.263-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://securetoken.googleapis.com duration=278.238762ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.299-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://www.googleapis.com duration=316.617745ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.302-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=320.433114ms Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 18:36:56 rivo volumio[3477]: info: Discovery: Getting this device information Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::volumioGetState Oct 02 18:36:56 rivo volumio[3477]: info: CorePlayQueue::getTrack 0 Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.381-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://functions.volumio.cloud duration=397.663294ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.386-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://google.com duration=405.227197ms Oct 02 18:36:56 rivo volumio[3477]: verbose: New Socket.io Connection to 192.168.1.117:3000 from 192.168.1.137 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 02 18:36:56 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.394-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://functions.volumio.cloud duration=411.951052ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.410-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=http://pushupdates.volumio.org duration=429.372714ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.420-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=http://plugins.volumio.org duration=438.408715ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.669-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://database.volumio.cloud duration=685.727981ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.685-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=702.49247ms Oct 02 18:36:56 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:56.776-07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=795.000501ms Oct 02 18:36:57 rivo volumio5-onboarding[4791]: time=2026-10-02T18:36:57.001-07:00 level=ERROR msg="endpoint is not reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.137:56485,00:00:00:00:00:00%13 @ 0x1acc090" latency=95.610265ms timeout=10s endpoint=http://cddb.volumio.org duration=1.018604669s error="Head \"http://gnudb.gnudb.org/~cddb/cddb.cgi/\": EOF" Oct 02 18:36:59 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:00 rivo volumio[3477]: info: CURURI: music-library Oct 02 18:37:00 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:00 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:00 rivo volumio[3477]: info: CURURI: music-library/USB Oct 02 18:37:00 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:02 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:02 rivo volumio[3477]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 18:37:02 rivo volumio[3477]: info: CoreStateMachine::ClearQueue Oct 02 18:37:02 rivo volumio[3477]: info: CoreStateMachine::stop Oct 02 18:37:02 rivo volumio[3477]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 18:37:02 rivo volumio[3477]: info: CorePlayQueue::clearPlayQueue Oct 02 18:37:02 rivo volumio[3477]: info: CorePlayQueue::saveQueue Oct 02 18:37:02 rivo volumio[3477]: info: CoreCommandRouter::volumioPushQueue Oct 02 18:37:02 rivo volumio[3477]: info: CoreStateMachine::addQueueItems Oct 02 18:37:02 rivo volumio[3477]: info: CorePlayQueue::addQueueItems Oct 02 18:37:02 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:02 rivo volumio[3477]: info: Adding Item to queue: music-library/USB/Samsung_T52 Oct 02 18:37:02 rivo volumio[3477]: info: Exploding uri music-library/USB/Samsung_T52 in service mpd Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:07 rivo volumio[3477]: info: CURURI: music-library/USB/Samsung_T52 Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:07 rivo volumio[3477]: info: CURURI: music-library/USB/Samsung_T52 Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:07 rivo volumio[3477]: info: CURURI: music-library/USB/Samsung_T52 Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:07 rivo volumio[3477]: info: CURURI: music-library/USB/Samsung_T52 Oct 02 18:37:07 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 02 18:37:07 rivo volumio[3477]: info: CoreStateMachine::ClearQueue Oct 02 18:37:07 rivo volumio[3477]: info: CoreStateMachine::stop Oct 02 18:37:07 rivo volumio[3477]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 18:37:07 rivo volumio[3477]: info: CorePlayQueue::clearPlayQueue Oct 02 18:37:07 rivo volumio[3477]: info: CorePlayQueue::saveQueue Oct 02 18:37:07 rivo volumio[3477]: info: CoreCommandRouter::volumioPushQueue Oct 02 18:37:07 rivo volumio[3477]: info: CoreStateMachine::addQueueItems Oct 02 18:37:07 rivo volumio[3477]: info: CorePlayQueue::addQueueItems Oct 02 18:37:07 rivo volumio[3477]: info: Preload queue cleared Oct 02 18:37:07 rivo volumio[3477]: info: Adding Item to queue: music-library/USB/Samsung_T52 Oct 02 18:37:07 rivo volumio[3477]: info: Exploding uri music-library/USB/Samsung_T52 in service mpd Oct 02 18:37:09 rivo volumio[3477]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 18:37:09 rivo volumio[3477]: info: CURURI: music-library/USB/Samsung_T52 Oct 02 18:37:09 rivo volumio[3477]: info: ALBUMART /albumart?cacheid=49&web=Patricia%20Barber/Monday%20Night%3A%20Recorded%20Live%20At%20The%20Green%20Mill%20-%20Chicago%2C%20IL%20%202%2F20%2F06/extralarge&path=%2Fmnt%2FUSB%2FSamsung_T52%2F%24RECYCLE.BIN%2F%24R00B9TQ.zip&metadata=false Oct 02 18:37:09 rivo volumio[3477]: info: URI /mnt/USB/Samsung_T52/$RECYCLE.BIN/$R00B9TQ.zip Oct 02 18:37:09 rivo volumio[3477]: info: ALBUMART /albumart?cacheid=49&web=Patricia%20Barber/Monday%20Night%3A%20Recorded%20Live%20At%20The%20Green%20Mill%20-%20Chicago%2C%20IL%20%202%2F20%2F06/extralarge&path=%2Fmnt%2FUSB%2FSamsung_T52%2F%24RECYCLE.BIN%2F%24R00B9TQ.zip&metadata=false Oct 02 18:37:09 rivo volumio[3477]: info: URI /mnt/USB/Samsung_T52/$RECYCLE.BIN/$R00B9TQ.zip Oct 02 18:37:09 rivo volumio[3477]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 18:37:09 rivo volumio[3477]: Error: Unable to resolve or reject the same promise twice Oct 02 18:37:09 rivo volumio[3477]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Oct 02 18:37:09 rivo volumio[3477]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Oct 02 18:37:09 rivo volumio[3477]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Oct 02 18:37:09 rivo volumio[3477]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Oct 02 18:37:09 rivo volumio[3477]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Oct 02 18:37:09 rivo volumio[3477]: at Socket.emit (node:events:514:28) Oct 02 18:37:09 rivo volumio[3477]: at addChunk (node:internal/streams/readable:343:12) Oct 02 18:37:09 rivo volumio[3477]: at readableAddChunk (node:internal/streams/readable:312:11) Oct 02 18:37:09 rivo volumio[3477]: at Readable.push (node:internal/streams/readable:253:10) Oct 02 18:37:09 rivo volumio[3477]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Oct 02 18:37:09 rivo volumio[3477]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 18:37:10 rivo sudo[31560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-02 18:36' Oct 02 18:37:10 rivo sudo[31560]: 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="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Sep 16 16:27:36 UTC 2026" VOLUMIO_VERSION="4.208" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="be0c94671215c2b1bf2440825a1e4211"