May 26 12:07:04 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 9 May 26 12:07:04 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:04 volumio-luigi volumio[1338]: info: Prefetching next song May 26 12:07:04 volumio-luigi volumio[1338]: info: DOING PREFETCH IN MPD May 26 12:07:04 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand add "NAS/flac_toshiba/VARI/Inti-Illimani - Antologia I 1973 - 1978 (2000)/11 - Dolencias.flac" May 26 12:07:04 volumio-luigi volumio[1338]: info: May 26 12:07:04 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:04 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:04 volumio-luigi volumio[1338]: info: sendMpdCommand add "NAS/flac_toshiba/VARI/Inti-Illimani - Antologia I 1973 - 1978 (2000)/11 - Dolencias.flac" took 5 milliseconds May 26 12:07:04 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand consume 1 May 26 12:07:04 volumio-luigi volumio[1338]: info: May 26 12:07:04 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:04 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:04 volumio-luigi volumio[1338]: info: May 26 12:07:04 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:04 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:04 volumio-luigi volumio[1338]: info: ------------------------------ 13ms May 26 12:07:04 volumio-luigi volumio[1338]: info: sendMpdCommand consume 1 took 10 milliseconds May 26 12:07:04 volumio-luigi volumio[1338]: info: ------------------------------ 10ms May 26 12:07:04 volumio-luigi volumio[1338]: info: ------------------------------ 8ms May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:08 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::getState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand status May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:08 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::getState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand status May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces system playlist update May 26 12:07:08 volumio-luigi volumio[1338]: info: Ignoring MPD Status Update May 26 12:07:08 volumio-luigi volumio[1338]: info: May 26 12:07:08 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::getState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand status May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::startPlaybackTimer May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 17ms May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand status took 19 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 15ms May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand status took 13 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 12ms May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand status took 11 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseState May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand playlistinfo took 6 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand playlistinfo took 5 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: info: sendMpdCommand playlistinfo took 4 milliseconds May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseTrackInfo May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseTrackInfo May 26 12:07:08 volumio-luigi volumio[1338]: verbose: ControllerMpd::parseTrackInfo May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::servicePushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"Dolencias","artist":"Inti-Illimani","album":"Antologia I 1973-1978","uri":"NAS/flac_toshiba/VARI/Inti-Illimani - Antologia I 1973 - 1978 (2000)/11 - Dolencias.flac","trackType":"flac"} May 26 12:07:08 volumio-luigi volumio[1338]: verbose: CURRENT POSITION 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState stateService play May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState currentStatus play May 26 12:07:08 volumio-luigi volumio[1338]: info: Received an update from plugin. extracting info from payload May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::servicePushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"Dolencias","artist":"Inti-Illimani","album":"Antologia I 1973-1978","uri":"NAS/flac_toshiba/VARI/Inti-Illimani - Antologia I 1973 - 1978 (2000)/11 - Dolencias.flac","trackType":"flac"} May 26 12:07:08 volumio-luigi volumio[1338]: verbose: CURRENT POSITION 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState stateService play May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState currentStatus play May 26 12:07:08 volumio-luigi volumio[1338]: info: Received an update from plugin. extracting info from payload May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: ControllerMpd::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::servicePushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"Dolencias","artist":"Inti-Illimani","album":"Antologia I 1973-1978","uri":"NAS/flac_toshiba/VARI/Inti-Illimani - Antologia I 1973 - 1978 (2000)/11 - Dolencias.flac","trackType":"flac"} May 26 12:07:08 volumio-luigi volumio[1338]: verbose: CURRENT POSITION 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState stateService play May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::syncState currentStatus play May 26 12:07:08 volumio-luigi volumio[1338]: info: Received an update from plugin. extracting info from payload May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:08 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:08 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 157ms May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 152ms May 26 12:07:08 volumio-luigi volumio[1338]: info: ------------------------------ 149ms May 26 12:07:09 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:07:09 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:07:09 volumio-luigi volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:07:09 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:07:17 volumio-luigi volumio[1338]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 26 12:07:17 volumio-luigi volumio[1338]: at createHttpError (/volumio/node_modules/send/index.js:979:12) May 26 12:07:17 volumio-luigi volumio[1338]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 26 12:07:17 volumio-luigi volumio[1338]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) May 26 12:07:17 volumio-luigi volumio[1338]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) May 26 12:07:17 volumio-luigi volumio[1338]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) May 26 12:07:17 volumio-luigi volumio[1338]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) May 26 12:07:17 volumio-luigi volumio[1338]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 26 12:07:17 volumio-luigi volumio[1338]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 26 12:07:25 volumio-luigi volumio[1338]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found May 26 12:07:25 volumio-luigi volumio[1338]: at createHttpError (/volumio/node_modules/send/index.js:979:12) May 26 12:07:25 volumio-luigi volumio[1338]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) May 26 12:07:25 volumio-luigi volumio[1338]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) May 26 12:07:25 volumio-luigi volumio[1338]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) May 26 12:07:25 volumio-luigi volumio[1338]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) May 26 12:07:25 volumio-luigi volumio[1338]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) May 26 12:07:25 volumio-luigi volumio[1338]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 26 12:07:25 volumio-luigi volumio[1338]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) May 26 12:07:53 volumio-luigi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 26 12:08:15 volumio-luigi volumio[1338]: info: Preload queue cleared May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioReplaceandPlayItems May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::ClearQueue May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::stop May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::stPlaybackTimer May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::updateTrackBlock May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrackBlock May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::pushState May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushState May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::serviceStop May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::getTrack 10 May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreCommandRouter::serviceStop May 26 12:08:15 volumio-luigi volumio[1338]: info: ControllerMpd::stop May 26 12:08:15 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand stop May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::clearPlayQueue May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::saveQueue May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreCommandRouter::volumioPushQueue May 26 12:08:15 volumio-luigi volumio[1338]: info: CoreStateMachine::addQueueItems May 26 12:08:15 volumio-luigi volumio[1338]: info: CorePlayQueue::addQueueItems May 26 12:08:15 volumio-luigi volumio[1338]: info: Preload queue cleared May 26 12:08:15 volumio-luigi volumio[1338]: info: Adding Item to queue: music-library/NAS/flac_toshiba/VARI/Various Artists - Pulp Fiction [Collector's Edition] (2002) FLAC May 26 12:08:15 volumio-luigi volumio[1338]: info: Exploding uri music-library/NAS/flac_toshiba/VARI/Various Artists - Pulp Fiction [Collector's Edition] (2002) FLAC in service mpd May 26 12:08:15 volumio-luigi volumio[1338]: info: sendMpdCommand stop took 123 milliseconds May 26 12:08:15 volumio-luigi volumio[1338]: info: May 26 12:08:15 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:08:15 volumio-luigi volumio[1338]: info: ControllerMpd::getState May 26 12:08:15 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand status May 26 12:08:15 volumio-luigi volumio[1338]: info: May 26 12:08:15 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:08:15 volumio-luigi volumio[1338]: info: ControllerMpd::getState May 26 12:08:15 volumio-luigi volumio[1338]: verbose: ControllerMpd::sendMpdCommand status May 26 12:08:15 volumio-luigi volumio[1338]: info: May 26 12:08:15 volumio-luigi volumio[1338]: ---------------------------- MPD announces state update: player May 26 12:08:15 volumio-luigi volumio[1338]: info: ALBUMART /albumart?cacheid=615&web=Dick%20Dale%20%26%20His%20Del-Tones/Pulp%20Fiction%20%5BCollector's%20Edition%5D/extralarge&path=%2Fmnt%2FNAS%2Fflac_toshiba%2FVARI%2FVarious%20Artists%20-%20Pulp%20Fiction%20%5BCollector's%20Edition%5D%20(2002)%20FLAC%2FVarious%20Artists%20-%20Pulp%20Fiction%20%5BCollector's%20Edition%5D.cue&metadata=false May 26 12:08:15 volumio-luigi volumio[1338]: info: URI /mnt/NAS/flac_toshiba/VARI/Various Artists - Pulp Fiction [Collector's Edition] (2002) FLAC/Various Artists - Pulp Fiction [Collector's Edition].cue May 26 12:08:15 volumio-luigi volumio[1338]: info: ALBUMART /albumart?cacheid=615&web=John%20Travolta%20%26%20Samuel%20L.%20Jackson/Pulp%20Fiction%20%5BCollector's%20Edition%5D/extralarge&path=%2Fmnt%2FNAS%2Fflac_toshiba%2FVARI%2FVarious%20Artists%20-%20Pulp%20Fiction%20%5BCollector's%20Edition%5D%20(2002)%20FLAC%2FVarious%20Artists%20-%20Pulp%20Fiction%20%5BCollector's%20Edition%5D.cue&metadata=false May 26 12:08:15 volumio-luigi volumio[1338]: info: URI /mnt/NAS/flac_toshiba/VARI/Various Artists - Pulp Fiction [Collector's Edition] (2002) FLAC/Various Artists - Pulp Fiction [Collector's Edition].cue May 26 12:08:15 volumio-luigi volumio[1338]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 12:08:15 volumio-luigi volumio[1338]: Error: Unable to resolve or reject the same promise twice May 26 12:08:15 volumio-luigi volumio[1338]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 26 12:08:15 volumio-luigi volumio[1338]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 May 26 12:08:15 volumio-luigi volumio[1338]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 26 12:08:15 volumio-luigi volumio[1338]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 26 12:08:15 volumio-luigi volumio[1338]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 26 12:08:15 volumio-luigi volumio[1338]: at Socket.emit (node:events:514:28) May 26 12:08:15 volumio-luigi volumio[1338]: at addChunk (node:internal/streams/readable:343:12) May 26 12:08:15 volumio-luigi volumio[1338]: at readableAddChunk (node:internal/streams/readable:312:11) May 26 12:08:15 volumio-luigi volumio[1338]: at Readable.push (node:internal/streams/readable:253:10) May 26 12:08:15 volumio-luigi volumio[1338]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 26 12:08:15 volumio-luigi volumio[1338]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 12:08:19 volumio-luigi sudo[27025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-26 12:07' May 26 12:08:19 volumio-luigi sudo[27025]: 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"