Dec 11 01:01:33 volumio volumio[1352]: info: CorePlayQueue::getTrack 9 Dec 11 01:01:33 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:33 volumio volumio[1352]: info: Prefetching next song Dec 11 01:01:33 volumio volumio[1352]: info: DOING PREFETCH IN MPD Dec 11 01:01:33 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Barbra Streisand - The Secret Of Life_ Partners, Volume 2 (2025) [FLAC] (24bit-96kHz)/04 - The Very Thought Of You.flac" Dec 11 01:01:33 volumio volumio[1352]: info: sendMpdCommand add "INTERNAL/Barbra Streisand - The Secret Of Life_ Partners, Volume 2 (2025) [FLAC] (24bit-96kHz)/04 - The Very Thought Of You.flac" took 0 milliseconds Dec 11 01:01:33 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 11 01:01:33 volumio volumio[1352]: info: Dec 11 01:01:33 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:33 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:33 volumio volumio[1352]: info: sendMpdCommand consume 1 took 0 milliseconds Dec 11 01:01:33 volumio volumio[1352]: info: Dec 11 01:01:33 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:33 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:33 volumio volumio[1352]: info: Dec 11 01:01:33 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:33 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:33 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:33 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:33 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::startPlaybackTimer Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:38 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces state update: player Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::getState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand status Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:38 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces state update: player Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::getState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand status Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces system playlist update Dec 11 01:01:38 volumio volumio[1352]: info: Ignoring MPD Status Update Dec 11 01:01:38 volumio volumio[1352]: info: Dec 11 01:01:38 volumio volumio[1352]: ---------------------------- MPD announces state update: player Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::getState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand status Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand status took 1 milliseconds Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand status took 1 milliseconds Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 1ms Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand status took 1 milliseconds Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseState Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseTrackInfo Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::servicePushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":233,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2991 Kbps","isStreaming":false,"title":"The Very Thought Of You","artist":"Barbra Streisand","album":"The Secret Of Life: Partners, Volume 2","uri":"INTERNAL/Barbra Streisand - The Secret Of Life_ Partners, Volume 2 (2025) [FLAC] (24bit-96kHz)/04 - The Very Thought Of You.flac","trackType":"flac"} Dec 11 01:01:38 volumio volumio[1352]: verbose: CURRENT POSITION 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState stateService play Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState currentStatus play Dec 11 01:01:38 volumio volumio[1352]: info: Received an update from plugin. extracting info from payload Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 5ms Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 01:01:38 volumio volumio[1352]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseTrackInfo Dec 11 01:01:38 volumio volumio[1352]: verbose: ControllerMpd::parseTrackInfo Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::servicePushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":233,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2991 Kbps","isStreaming":false,"title":"The Very Thought Of You","artist":"Barbra Streisand","album":"The Secret Of Life: Partners, Volume 2","uri":"INTERNAL/Barbra Streisand - The Secret Of Life_ Partners, Volume 2 (2025) [FLAC] (24bit-96kHz)/04 - The Very Thought Of You.flac","trackType":"flac"} Dec 11 01:01:38 volumio volumio[1352]: verbose: CURRENT POSITION 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState stateService play Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState currentStatus play Dec 11 01:01:38 volumio volumio[1352]: info: Received an update from plugin. extracting info from payload Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: ControllerMpd::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::servicePushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84,"duration":233,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2991 Kbps","isStreaming":false,"title":"The Very Thought Of You","artist":"Barbra Streisand","album":"The Secret Of Life: Partners, Volume 2","uri":"INTERNAL/Barbra Streisand - The Secret Of Life_ Partners, Volume 2 (2025) [FLAC] (24bit-96kHz)/04 - The Very Thought Of You.flac","trackType":"flac"} Dec 11 01:01:38 volumio volumio[1352]: verbose: CURRENT POSITION 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState stateService play Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::syncState currentStatus play Dec 11 01:01:38 volumio volumio[1352]: info: Received an update from plugin. extracting info from payload Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:01:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:01:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 8ms Dec 11 01:01:38 volumio volumio[1352]: info: ------------------------------ 9ms Dec 11 01:02:38 volumio volumio[1352]: info: Preload queue cleared Dec 11 01:02:38 volumio volumio[1352]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::ClearQueue Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::stop Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::stPlaybackTimer Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::updateTrackBlock Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::getTrackBlock Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::pushState Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:02:38 volumio volumio[1352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 01:02:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushState Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::serviceStop Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::getTrack 3 Dec 11 01:02:38 volumio volumio[1352]: info: CoreCommandRouter::serviceStop Dec 11 01:02:38 volumio volumio[1352]: info: ControllerMpd::stop Dec 11 01:02:38 volumio volumio[1352]: verbose: ControllerMpd::sendMpdCommand stop Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::clearPlayQueue Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::saveQueue Dec 11 01:02:38 volumio volumio[1352]: info: CoreCommandRouter::volumioPushQueue Dec 11 01:02:38 volumio volumio[1352]: info: CoreStateMachine::addQueueItems Dec 11 01:02:38 volumio volumio[1352]: info: CorePlayQueue::addQueueItems Dec 11 01:02:38 volumio volumio[1352]: info: Preload queue cleared Dec 11 01:02:38 volumio volumio[1352]: info: Adding Item to queue: music-library/INTERNAL/Bad Boys Blue - My Blue World (1988, Coconut 260 222-217) FLAC Dec 11 01:02:38 volumio volumio[1352]: info: Exploding uri music-library/INTERNAL/Bad Boys Blue - My Blue World (1988, Coconut 260 222-217) FLAC in service mpd Dec 11 01:02:38 volumio volumio[1352]: info: Dec 11 01:02:38 volumio volumio[1352]: ---------------------------- MPD announces state update: player Dec 11 01:02:38 volumio volumio[1352]: info: ALBUMART /albumart?cacheid=232&web=Bad%20Boys%20Blue/My%20Blue%20World/extralarge&path=%2Fmnt%2FINTERNAL%2FBad%20Boys%20Blue%20-%20My%20Blue%20World%20(1988%2C%20Coconut%20260%20222-217)%20FLAC%2FBad%20Boys%20Blue%20-%20My%20Blue%20World.cue&metadata=false Dec 11 01:02:38 volumio volumio[1352]: info: URI /mnt/INTERNAL/Bad Boys Blue - My Blue World (1988, Coconut 260 222-217) FLAC/Bad Boys Blue - My Blue World.cue Dec 11 01:02:38 volumio volumio[1352]: info: ALBUMART /albumart?cacheid=232&web=Bad%20Boys%20Blue/My%20Blue%20World/extralarge&path=%2Fmnt%2FINTERNAL%2FBad%20Boys%20Blue%20-%20My%20Blue%20World%20(1988%2C%20Coconut%20260%20222-217)%20FLAC%2FBad%20Boys%20Blue%20-%20My%20Blue%20World.cue&metadata=false Dec 11 01:02:38 volumio volumio[1352]: info: URI /mnt/INTERNAL/Bad Boys Blue - My Blue World (1988, Coconut 260 222-217) FLAC/Bad Boys Blue - My Blue World.cue Dec 11 01:02:38 volumio volumio[1352]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 01:02:38 volumio volumio[1352]: Error: Unable to resolve or reject the same promise twice Dec 11 01:02:38 volumio volumio[1352]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 11 01:02:38 volumio volumio[1352]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 11 01:02:38 volumio volumio[1352]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 11 01:02:38 volumio volumio[1352]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 11 01:02:38 volumio volumio[1352]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 11 01:02:38 volumio volumio[1352]: at Socket.emit (node:events:514:28) Dec 11 01:02:38 volumio volumio[1352]: at addChunk (node:internal/streams/readable:343:12) Dec 11 01:02:38 volumio volumio[1352]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 11 01:02:38 volumio volumio[1352]: at Readable.push (node:internal/streams/readable:253:10) Dec 11 01:02:38 volumio volumio[1352]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 11 01:02:38 volumio volumio[1352]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 01:02:38 volumio sudo[10856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 01:01' Dec 11 01:02:38 volumio sudo[10856]: 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="d660210290419ed25c2683f3b8b26a5b4eb5c7dc" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 10 20:22:33 UTC 2025" VOLUMIO_VERSION="4.078" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="f16806b881fcb20fea0fbc0be6d426f4"