Dec 29 13:12:17 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:17 volumio volumio[1212]: info: CorePlayQueue::getTrack 1 Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Dec 29 13:12:21 volumio volumio[1212]: info: Ignoring MPD Status Update Dec 29 13:12:21 volumio upmpdcli[1358]: :2:../src/mpdcli.cxx:336::song = mpd_run_get_queue_song_pos(m_conn, (unsigned int)pos) failed: Bad song index Dec 29 13:12:21 volumio upmpdcli[1358]: :2:../src/mpdcli.cxx:339::song = mpd_run_get_queue_song_pos(m_conn, (unsigned int)pos) server error: 2 Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces state update: player Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::getState Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Dec 29 13:12:21 volumio volumio[1212]: info: Ignoring MPD Status Update Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces state update: player Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::getState Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces system playlist update Dec 29 13:12:21 volumio volumio[1212]: info: Ignoring MPD Status Update Dec 29 13:12:21 volumio volumio[1212]: info: Dec 29 13:12:21 volumio volumio[1212]: ---------------------------- MPD announces state update: player Dec 29 13:12:21 volumio volumio[1212]: error: updateQueue error: null Dec 29 13:12:21 volumio volumio[1212]: error: updateQueue error: null Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::getState Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::sendMpdCommand status Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 8ms Dec 29 13:12:21 volumio volumio[1212]: info: sendMpdCommand status took 6 milliseconds Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 5ms Dec 29 13:12:21 volumio volumio[1212]: info: sendMpdCommand status took 4 milliseconds Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::parseState Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::parseState Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 13:12:21 volumio volumio[1212]: verbose: CURRENT POSITION 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState stateService stop Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus play Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::stPlaybackTimer Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 13:12:21 volumio volumio[1212]: verbose: CURRENT POSITION 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState stateService stop Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus stop Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: No code Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 58ms Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 55ms Dec 29 13:12:21 volumio volumio[1212]: error: updateQueue error: null Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 56ms Dec 29 13:12:21 volumio volumio[1212]: info: sendMpdCommand status took 55 milliseconds Dec 29 13:12:21 volumio volumio[1212]: verbose: ControllerMpd::parseState Dec 29 13:12:21 volumio volumio[1212]: info: ControllerMpd::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::servicePushState Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 29 13:12:21 volumio volumio[1212]: verbose: CURRENT POSITION 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState stateService stop Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::syncState currentStatus stop Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: No code Dec 29 13:12:21 volumio volumio[1212]: info: CoreStateMachine::pushState Dec 29 13:12:21 volumio volumio[1212]: info: CorePlayQueue::getTrack 0 Dec 29 13:12:21 volumio volumio[1212]: info: CoreCommandRouter::volumioPushState Dec 29 13:12:21 volumio volumio[1212]: info: ------------------------------ 85ms Dec 29 13:12:50 volumio volumio[1212]: info: Preload queue cleared Dec 29 13:12:50 volumio volumio[1212]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 29 13:12:50 volumio volumio[1212]: info: CoreStateMachine::ClearQueue Dec 29 13:12:50 volumio volumio[1212]: info: CoreStateMachine::stop Dec 29 13:12:50 volumio volumio[1212]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 29 13:12:50 volumio volumio[1212]: info: CorePlayQueue::clearPlayQueue Dec 29 13:12:50 volumio volumio[1212]: info: CorePlayQueue::saveQueue Dec 29 13:12:50 volumio volumio[1212]: info: CoreCommandRouter::volumioPushQueue Dec 29 13:12:50 volumio volumio[1212]: info: CoreStateMachine::addQueueItems Dec 29 13:12:50 volumio volumio[1212]: info: CorePlayQueue::addQueueItems Dec 29 13:12:50 volumio volumio[1212]: info: Preload queue cleared Dec 29 13:12:50 volumio volumio[1212]: info: Adding Item to queue: music-library/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994) Dec 29 13:12:50 volumio volumio[1212]: info: Exploding uri music-library/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994) in service mpd Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Lloyd%20Cole%20%26%20The%20Commotions/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F01.%20Lloyd%20Cole%20%26%20The%20Commotions%20-%20My%20Bag%20(Dancing%20Mix).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/01. Lloyd Cole & The Commotions - My Bag (Dancing Mix).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Cactus%20World%20News/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F02.%20Cactus%20World%20News%20-%20Worlds%20Appart%20(12''%20Version).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/02. Cactus World News - Worlds Appart (12'' Version).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=The%20Danse%20Society/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F03.%20The%20Danse%20Society%20-%20Hold%20On%20(12''%20Version).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/03. The Danse Society - Hold On (12'' Version).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=The%20Essence%20(4)/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F04.%20The%20Essence%20(4)%20-%20A%20Mirage%20(Extended%20Version).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/04. The Essence (4) - A Mirage (Extended Version).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Midnight%20(4)/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F05.%20Midnight%20(4)%20-%20Run%20With%20You%20(Late%20Night%20Mix).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/05. Midnight (4) - Run With You (Late Night Mix).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=The%20Game%20(3)/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F06.%20The%20Game%20(3)%20-%20Walk%20Away.flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/06. The Game (3) - Walk Away.flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Gene%20Loves%20Jezebel/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F07.%20Gene%20Loves%20Jezebel%20-%20Desire%20(12''%20Version).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/07. Gene Loves Jezebel - Desire (12'' Version).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Gary%20Numan/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2F08.%20Gary%20Numan%20-%20All%20Across%20The%20Nation%20(Extended%20Mix).flac&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/08. Gary Numan - All Across The Nation (Extended Mix).flac Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Lloyd%20Cole%20%26%20The%20Commotions/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2FVA%20-%20Maxi%20Pop%20(CD1).cue&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/VA - Maxi Pop (CD1).cue Dec 29 13:12:50 volumio volumio[1212]: info: ALBUMART /albumart?cacheid=646&web=Cactus%20World%20News/Maxi%20Pop%20(CD1)/extralarge&path=%2Fmnt%2FUSB%2FMUSICA%2FMUSICA%2FMAXI%20POP%2FMaxi%20Pop%201%20%20(1994)%2FCD1%2FVA%20-%20Maxi%20Pop%20(CD1).cue&metadata=false Dec 29 13:12:50 volumio volumio[1212]: info: URI /mnt/USB/MUSICA/MUSICA/MAXI POP/Maxi Pop 1 (1994)/CD1/VA - Maxi Pop (CD1).cue Dec 29 13:12:50 volumio volumio[1212]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 13:12:50 volumio volumio[1212]: Error: Unable to resolve or reject the same promise twice Dec 29 13:12:50 volumio volumio[1212]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 29 13:12:50 volumio volumio[1212]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 29 13:12:50 volumio volumio[1212]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 29 13:12:50 volumio volumio[1212]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 29 13:12:50 volumio volumio[1212]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 29 13:12:50 volumio volumio[1212]: at Socket.emit (node:events:514:28) Dec 29 13:12:50 volumio volumio[1212]: at addChunk (node:internal/streams/readable:343:12) Dec 29 13:12:50 volumio volumio[1212]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 29 13:12:50 volumio volumio[1212]: at Readable.push (node:internal/streams/readable:253:10) Dec 29 13:12:50 volumio volumio[1212]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 29 13:12:50 volumio volumio[1212]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 29 13:12:51 volumio sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-29 13:11' Dec 29 13:12:51 volumio sudo[2423]: 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"