May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::volumioPause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::servicePause May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::servicePause May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::pause May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand pause May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand pause took 2 milliseconds May 25 15:24:03 volumio volumio[1153]: info: May 25 15:24:03 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:24:03 volumio volumio[1153]: info: May 25 15:24:03 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:24:03 volumio volumio[1153]: info: May 25 15:24:03 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand status took 2 milliseconds May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand status took 1 milliseconds May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand status took 0 milliseconds May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseState May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 15:24:03 volumio volumio[1153]: info: sendMpdCommand playlistinfo took 1 milliseconds May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo May 25 15:24:03 volumio volumio[1153]: verbose: ControllerMpd::parseTrackInfo May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::pushState May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::servicePushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":140852,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Toccata","artist":"Paul Mauriat","album":"The Most Beautiful Music In Your Dreams","uri":"NAS/JM_NAS1/POPS/The Most Beautiful Music In Your Dreams/방송시그널Dreams 1 - The Most Beautiful Music In Your Dreams, 2CD (2003) [FLAC]/Dreams #1 CD1/07. Paul Mauriat - Toccata.flac","trackType":"flac"} May 25 15:24:03 volumio volumio[1153]: verbose: CURRENT POSITION 6 May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState stateService pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::pushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::pushState May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::servicePushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":140852,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Toccata","artist":"Paul Mauriat","album":"The Most Beautiful Music In Your Dreams","uri":"NAS/JM_NAS1/POPS/The Most Beautiful Music In Your Dreams/방송시그널Dreams 1 - The Most Beautiful Music In Your Dreams, 2CD (2003) [FLAC]/Dreams #1 CD1/07. Paul Mauriat - Toccata.flac","trackType":"flac"} May 25 15:24:03 volumio volumio[1153]: verbose: CURRENT POSITION 6 May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState stateService pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::pushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer May 25 15:24:03 volumio volumio[1153]: info: ControllerMpd::pushState May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::servicePushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":140852,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"933 Kbps","isStreaming":false,"title":"Toccata","artist":"Paul Mauriat","album":"The Most Beautiful Music In Your Dreams","uri":"NAS/JM_NAS1/POPS/The Most Beautiful Music In Your Dreams/방송시그널Dreams 1 - The Most Beautiful Music In Your Dreams, 2CD (2003) [FLAC]/Dreams #1 CD1/07. Paul Mauriat - Toccata.flac","trackType":"flac"} May 25 15:24:03 volumio volumio[1153]: verbose: CURRENT POSITION 6 May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState stateService pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::syncState currentStatus pause May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::pushState May 25 15:24:03 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:24:03 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState May 25 15:24:03 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer May 25 15:24:03 volumio volumio[1153]: info: ------------------------------ 11ms May 25 15:24:03 volumio volumio[1153]: info: ------------------------------ 10ms May 25 15:24:03 volumio volumio[1153]: info: ------------------------------ 11ms May 25 15:24:12 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 15:24:12 volumio volumio[1153]: info: CURURI: music-library/NAS/JM_NAS1/Classic May 25 15:24:12 volumio volumio[1153]: info: Preload queue cleared May 25 15:24:27 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 25 15:24:27 volumio volumio[1153]: info: CURURI: music-library/NAS/JM_NAS1/Classic/101 Classics - The Best Loved Classical Melodies 8CD (NAXOS) [APE] May 25 15:24:27 volumio volumio[1153]: info: Preload queue cleared May 25 15:24:44 volumio systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. May 25 15:24:44 volumio systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... May 25 15:24:44 volumio systemd[1]: welcome.service: Deactivated successfully. May 25 15:24:44 volumio systemd[1]: Stopped welcome.service - Show a welcome message on console. May 25 15:24:44 volumio systemd[1]: Stopping welcome.service - Show a welcome message on console... May 25 15:24:44 volumio systemd[1]: Starting welcome.service - Show a welcome message on console... May 25 15:24:44 volumio welcome[26272]: Resolved ip:[1] 192.168.0.50 May 25 15:24:44 volumio systemd[1]: Finished welcome.service - Show a welcome message on console. May 25 15:24:44 volumio systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. May 25 15:25:31 volumio volumio[1153]: info: Preload queue cleared May 25 15:25:31 volumio volumio[1153]: info: CoreCommandRouter::volumioReplaceandPlayItems May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::ClearQueue May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::stop May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::setConsumeUpdateService undefined May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::updateTrackBlock May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::getTrackBlock May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::stPlaybackTimer May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::pushState May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:25:31 volumio volumio[1153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 25 15:25:31 volumio volumio[1153]: info: CoreCommandRouter::volumioPushState May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::serviceStop May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::getTrack 6 May 25 15:25:31 volumio volumio[1153]: info: CoreCommandRouter::serviceStop May 25 15:25:31 volumio volumio[1153]: info: ControllerMpd::stop May 25 15:25:31 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand stop May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::clearPlayQueue May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::saveQueue May 25 15:25:31 volumio volumio[1153]: info: CoreCommandRouter::volumioPushQueue May 25 15:25:31 volumio volumio[1153]: info: CoreStateMachine::addQueueItems May 25 15:25:31 volumio volumio[1153]: info: CorePlayQueue::addQueueItems May 25 15:25:31 volumio volumio[1153]: info: Preload queue cleared May 25 15:25:31 volumio volumio[1153]: info: Adding Item to queue: music-library/NAS/JM_NAS1/Classic/Opera Gold：100 Great Tracks from Decca, The Opera Company, 6CD (Decca, 2016) [FLAC image+.cue] May 25 15:25:31 volumio volumio[1153]: info: Exploding uri music-library/NAS/JM_NAS1/Classic/Opera Gold：100 Great Tracks from Decca, The Opera Company, 6CD (Decca, 2016) [FLAC image+.cue] in service mpd May 25 15:25:31 volumio volumio[1153]: info: sendMpdCommand stop took 22 milliseconds May 25 15:25:31 volumio volumio[1153]: info: May 25 15:25:31 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:25:31 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:25:31 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:25:31 volumio volumio[1153]: info: May 25 15:25:31 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:25:31 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:25:31 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:25:31 volumio volumio[1153]: info: May 25 15:25:31 volumio volumio[1153]: ---------------------------- MPD announces state update: player May 25 15:25:31 volumio volumio[1153]: info: ControllerMpd::getState May 25 15:25:31 volumio volumio[1153]: verbose: ControllerMpd::sendMpdCommand status May 25 15:25:31 volumio volumio[1153]: info: ALBUMART /albumart?cacheid=255&web=Luciano%20Pavarotti/Opera%20Gold%3F%3F100%20great%20tracks%20CD1/extralarge&path=%2Fmnt%2FNAS%2FJM_NAS1%2FClassic%2FOpera%20Gold%EF%BC%9A100%20Great%20Tracks%20from%20Decca%2C%20The%20Opera%20Company%2C%206CD%20(Decca%2C%202016)%20%5BFLAC%20image%2B.cue%5D%2FCD1.cue&metadata=false May 25 15:25:31 volumio volumio[1153]: info: URI /mnt/NAS/JM_NAS1/Classic/Opera Gold：100 Great Tracks from Decca, The Opera Company, 6CD (Decca, 2016) [FLAC image+.cue]/CD1.cue May 25 15:25:31 volumio volumio[1153]: info: ALBUMART /albumart?cacheid=255&web=Marilyn%20Horne/Opera%20Gold%3F%3F100%20great%20tracks%20CD1/extralarge&path=%2Fmnt%2FNAS%2FJM_NAS1%2FClassic%2FOpera%20Gold%EF%BC%9A100%20Great%20Tracks%20from%20Decca%2C%20The%20Opera%20Company%2C%206CD%20(Decca%2C%202016)%20%5BFLAC%20image%2B.cue%5D%2FCD1.cue&metadata=false May 25 15:25:31 volumio volumio[1153]: info: URI /mnt/NAS/JM_NAS1/Classic/Opera Gold：100 Great Tracks from Decca, The Opera Company, 6CD (Decca, 2016) [FLAC image+.cue]/CD1.cue May 25 15:25:31 volumio volumio[1153]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:25:31 volumio volumio[1153]: Error: Unable to resolve or reject the same promise twice May 25 15:25:31 volumio volumio[1153]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 25 15:25:31 volumio volumio[1153]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 May 25 15:25:31 volumio volumio[1153]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) May 25 15:25:31 volumio volumio[1153]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) May 25 15:25:31 volumio volumio[1153]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 25 15:25:31 volumio volumio[1153]: at Socket.emit (node:events:514:28) May 25 15:25:31 volumio volumio[1153]: at addChunk (node:internal/streams/readable:343:12) May 25 15:25:31 volumio volumio[1153]: at readableAddChunk (node:internal/streams/readable:312:11) May 25 15:25:31 volumio volumio[1153]: at Readable.push (node:internal/streams/readable:253:10) May 25 15:25:31 volumio volumio[1153]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 25 15:25:31 volumio volumio[1153]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 25 15:25:31 volumio sudo[26384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-25 15:24' May 25 15:25:31 volumio sudo[26384]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"