Aug 30 16:28:10 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:10.461+05:30 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s Aug 30 16:28:10 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:10.552+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=90.267303ms Aug 30 16:28:10 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:10.863+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://securetoken.googleapis.com duration=400.971024ms Aug 30 16:28:10 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:10.864+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://www.googleapis.com duration=401.225169ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.151+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=http://pushupdates.volumio.org duration=688.645594ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.281+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://google.com duration=818.748136ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.361+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://functions.volumio.cloud duration=898.670052ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.361+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://functions.volumio.cloud duration=898.839809ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.409+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=947.308105ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.452+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=989.700959ms Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.524+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=1.061887269s Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.684+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=https://database.volumio.cloud duration=1.221689085s Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.917+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=http://cddb.volumio.org duration=1.454404738s Aug 30 16:28:11 volumio volumio5-onboarding[1582]: time=2026-08-30T16:28:11.993+05:30 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.4:58878,00:00:00:00:00:00%02 @ 0x190e7e0" latency=-614.234133ms timeout=10s endpoint=http://plugins.volumio.org duration=1.530912735s Aug 30 16:28:12 volumio volumio[1230]: info: CoreCommandRouter::volumioPlay Aug 30 16:28:12 volumio volumio[1230]: info: CoreStateMachine::play index undefined Aug 30 16:28:12 volumio volumio[1230]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 16:28:12 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 30 16:28:12 volumio volumio[1230]: info: CoreStateMachine::startPlaybackTimer Aug 30 16:28:12 volumio volumio[1230]: info: CorePlayQueue::getTrack 0 Aug 30 16:28:12 volumio volumio[1230]: info: [1788087492229] ControllerSpotify::clearAddPlayTrack Aug 30 16:28:12 volumio volumio[1230]: info: Sending Spotify command with payload to local API: /player/play Aug 30 16:28:14 volumio volumio[1230]: info: CoreCommandRouter::volumioGetQueue Aug 30 16:28:14 volumio volumio[1230]: info: CoreStateMachine::getQueue Aug 30 16:28:14 volumio volumio[1230]: info: CorePlayQueue::getQueue Aug 30 16:28:16 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:28:16 volumio volumio[1230]: info: CURURI: music-library Aug 30 16:28:16 volumio volumio[1230]: info: Preload queue cleared Aug 30 16:28:18 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:28:18 volumio volumio[1230]: info: CURURI: music-library/USB Aug 30 16:28:18 volumio volumio[1230]: info: Preload queue cleared Aug 30 16:28:19 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:28:19 volumio volumio[1230]: info: CURURI: music-library/USB/Music_Lib Aug 30 16:28:19 volumio volumio[1230]: info: Preload queue cleared Aug 30 16:28:21 volumio volumio[1230]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 16:28:21 volumio volumio[1230]: info: CURURI: music-library/USB/Music_Lib/Flac Aug 30 16:28:21 volumio volumio[1230]: info: Preload queue cleared Aug 30 16:28:27 volumio volumio[1230]: info: CoreCommandRouter::volumioAddQueueItems Aug 30 16:28:27 volumio volumio[1230]: info: CoreStateMachine::addQueueItems Aug 30 16:28:27 volumio volumio[1230]: info: CorePlayQueue::addQueueItems Aug 30 16:28:27 volumio volumio[1230]: info: Preload queue cleared Aug 30 16:28:27 volumio volumio[1230]: info: Adding Item to queue: music-library/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac] Aug 30 16:28:27 volumio volumio[1230]: info: Exploding uri music-library/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac] in service mpd Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Florence%20And%20The%20Machine/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F01%20Florence%20And%20The%20Machine%20-%20Spectrum%20Say%20My%20Name%20%5BCalvin%20Harris%20Remix%20Radio%20Edit%5D.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/01 Florence And The Machine - Spectrum Say My Name [Calvin Harris Remix Radio Edit].flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Roll%20Deep/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F02%20Roll%20Deep%20-%20Good%20Times.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/02 Roll Deep - Good Times.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=The%20Black%20Eyed%20Peas/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F03%20The%20Black%20Eyed%20Peas%20-%20I%20Gotta%20Feeling.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/03 The Black Eyed Peas - I Gotta Feeling.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Pixie%20Lott/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F04%20Pixie%20Lott%20-%20Boys%20And%20Girls.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/04 Pixie Lott - Boys And Girls.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Duffy/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F05%20Duffy%20-%20Mercy.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/05 Duffy - Mercy.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=The%20Pussycat%20Dolls/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F06%20The%20Pussycat%20Dolls%20-%20Don't%20Cha.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/06 The Pussycat Dolls - Don't Cha.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Scissor%20Sisters/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F07%20Scissor%20Sisters%20-%20I%20Don't%20Feel%20Like%20Dancing.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/07 Scissor Sisters - I Don't Feel Like Dancing.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Kleerup%20-%20Robyn/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F08%20Kleerup%20-%20Robyn%20-%20Every%20Heartbeat.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/08 Kleerup - Robyn - Every Heartbeat.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Cheryl%20Cole/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F09%20Cheryl%20Cole%20-%20Fight%20For%20This%20Love.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/09 Cheryl Cole - Fight For This Love.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=John%20Newman/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F10%20John%20Newman%20-%20Love%20Me%20Again.flac&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/10 John Newman - Love Me Again.flac Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Florence%20And%20The%20Machine/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F101%20Number%201%20Hits%20%5BCD-1%5D.cue&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/101 Number 1 Hits [CD-1].cue Aug 30 16:28:28 volumio volumio[1230]: info: ALBUMART /albumart?cacheid=107&web=Roll%20Deep/101%20Number%201%20Hits%20%5BCD-1%5D/extralarge&path=%2Fmnt%2FUSB%2FMusic_Lib%2FFlac%2F101%20Number%201%20Hits%20-%20101%20Hits%20On%205%20CDs%202017%20%5BFlac%5D%2F101%20Number%201%20Hits%20On%205%20CDs%2F101%20Number%201%20Hits%20%5BCD-1%5D%2F101%20Number%201%20Hits%20%5BCD-1%5D.cue&metadata=false Aug 30 16:28:28 volumio volumio[1230]: info: URI /mnt/USB/Music_Lib/Flac/101 Number 1 Hits - 101 Hits On 5 CDs 2017 [Flac]/101 Number 1 Hits On 5 CDs/101 Number 1 Hits [CD-1]/101 Number 1 Hits [CD-1].cue Aug 30 16:28:28 volumio volumio[1230]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:28:28 volumio volumio[1230]: Error: Unable to resolve or reject the same promise twice Aug 30 16:28:28 volumio volumio[1230]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 30 16:28:28 volumio volumio[1230]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Aug 30 16:28:28 volumio volumio[1230]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 30 16:28:28 volumio volumio[1230]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 30 16:28:28 volumio volumio[1230]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 30 16:28:28 volumio volumio[1230]: at Socket.emit (node:events:514:28) Aug 30 16:28:28 volumio volumio[1230]: at addChunk (node:internal/streams/readable:343:12) Aug 30 16:28:28 volumio volumio[1230]: at readableAddChunk (node:internal/streams/readable:312:11) Aug 30 16:28:28 volumio volumio[1230]: at Readable.push (node:internal/streams/readable:253:10) Aug 30 16:28:28 volumio volumio[1230]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Aug 30 16:28:28 volumio volumio[1230]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 16:28:31 volumio sudo[2419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 16:27' Aug 30 16:28:31 volumio sudo[2419]: 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"