Jan 27 17:16:00 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:00 volumio upmpdcli[5631]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:16:00 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:16:00 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:16:15 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 163. Jan 27 17:16:15 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:15 volumio upmpdcli[5660]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:16:15 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:16:15 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:16:30 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 164. Jan 27 17:16:30 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:30 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:30 volumio upmpdcli[5690]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:16:30 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:16:30 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:16:45 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 165. Jan 27 17:16:45 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:45 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:16:45 volumio upmpdcli[5706]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:16:45 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:16:45 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:17:00 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 166. Jan 27 17:17:00 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:17:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:17:01 volumio upmpdcli[5735]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:17:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:17:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:17:08 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:08 volumio volumio[1195]: info: CorePlayQueue::getTrack 1 Jan 27 17:17:14 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Jan 27 17:17:14 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d1c580 00000000 00000000 0e000000 02028000 Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces system playlist update Jan 27 17:17:14 volumio volumio[1195]: info: Ignoring MPD Status Update Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces state update: player Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::getState Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces system playlist update Jan 27 17:17:14 volumio volumio[1195]: info: Ignoring MPD Status Update Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces state update: player Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::getState Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces system playlist update Jan 27 17:17:14 volumio volumio[1195]: info: Ignoring MPD Status Update Jan 27 17:17:14 volumio volumio[1195]: info: Jan 27 17:17:14 volumio volumio[1195]: ---------------------------- MPD announces state update: player Jan 27 17:17:14 volumio volumio[1195]: error: updateQueue error: null Jan 27 17:17:14 volumio volumio[1195]: error: updateQueue error: null Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::getState Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::sendMpdCommand status Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 3ms Jan 27 17:17:14 volumio volumio[1195]: info: sendMpdCommand status took 3 milliseconds Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 2ms Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::parseState Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::servicePushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: 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} Jan 27 17:17:14 volumio volumio[1195]: verbose: CURRENT POSITION 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState stateService stop Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState currentStatus play Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::stPlaybackTimer Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 16ms Jan 27 17:17:14 volumio volumio[1195]: error: updateQueue error: null Jan 27 17:17:14 volumio volumio[1195]: info: sendMpdCommand status took 15 milliseconds Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 16ms Jan 27 17:17:14 volumio volumio[1195]: info: sendMpdCommand status took 15 milliseconds Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::parseState Jan 27 17:17:14 volumio volumio[1195]: verbose: ControllerMpd::parseState Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::servicePushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: 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} Jan 27 17:17:14 volumio volumio[1195]: verbose: CURRENT POSITION 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState stateService stop Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: No code Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: ControllerMpd::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::servicePushState Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: 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} Jan 27 17:17:14 volumio volumio[1195]: verbose: CURRENT POSITION 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState stateService stop Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::syncState currentStatus stop Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: No code Jan 27 17:17:14 volumio volumio[1195]: info: CoreStateMachine::pushState Jan 27 17:17:14 volumio volumio[1195]: info: CorePlayQueue::getTrack 0 Jan 27 17:17:14 volumio volumio[1195]: info: CoreCommandRouter::volumioPushState Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 44ms Jan 27 17:17:14 volumio volumio[1195]: info: ------------------------------ 45ms Jan 27 17:17:16 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 167. Jan 27 17:17:16 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:17:16 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 27 17:17:16 volumio upmpdcli[5751]: Could not open config: /tmp/upmpdcli.conf Jan 27 17:17:16 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 27 17:17:16 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 27 17:17:27 volumio volumio[1195]: info: Preload queue cleared Jan 27 17:17:27 volumio volumio[1195]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 27 17:17:27 volumio volumio[1195]: info: CoreStateMachine::ClearQueue Jan 27 17:17:27 volumio volumio[1195]: info: CoreStateMachine::stop Jan 27 17:17:27 volumio volumio[1195]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 27 17:17:27 volumio volumio[1195]: info: CorePlayQueue::clearPlayQueue Jan 27 17:17:27 volumio volumio[1195]: info: CorePlayQueue::saveQueue Jan 27 17:17:27 volumio volumio[1195]: info: CoreCommandRouter::volumioPushQueue Jan 27 17:17:27 volumio volumio[1195]: info: CoreStateMachine::addQueueItems Jan 27 17:17:27 volumio volumio[1195]: info: CorePlayQueue::addQueueItems Jan 27 17:17:27 volumio volumio[1195]: info: Preload queue cleared Jan 27 17:17:27 volumio volumio[1195]: info: Adding Item to queue: music-library/NAS/D47/ECM 2624 New Series - Hosokawa, Mozart - Momo Kodama, Mito Chamber Orchestra, Seiji Ozawa - 2021 Jan 27 17:17:27 volumio volumio[1195]: info: Exploding uri music-library/NAS/D47/ECM 2624 New Series - Hosokawa, Mozart - Momo Kodama, Mito Chamber Orchestra, Seiji Ozawa - 2021 in service mpd Jan 27 17:17:27 volumio volumio[1195]: info: ALBUMART /albumart?cacheid=322&web=Momo%20Kodama%2C%20Mito%20Chamber%20Orchestra%2C%20Seiji%20Ozawa/Hosokawa%20%2F%20Mozart/extralarge&path=%2Fmnt%2FNAS%2FD47%2FECM%202624%20New%20Series%20-%20Hosokawa%2C%20Mozart%20-%20Momo%20Kodama%2C%20Mito%20Chamber%20Orchestra%2C%20Seiji%20Ozawa%20-%202021%2FHosokawa%2C%20Mozart.cue&metadata=false Jan 27 17:17:27 volumio volumio[1195]: info: URI /mnt/NAS/D47/ECM 2624 New Series - Hosokawa, Mozart - Momo Kodama, Mito Chamber Orchestra, Seiji Ozawa - 2021/Hosokawa, Mozart.cue Jan 27 17:17:27 volumio volumio[1195]: info: ALBUMART /albumart?cacheid=322&web=Momo%20Kodama%2C%20Mito%20Chamber%20Orchestra%2C%20Seiji%20Ozawa/Hosokawa%20%2F%20Mozart/extralarge&path=%2Fmnt%2FNAS%2FD47%2FECM%202624%20New%20Series%20-%20Hosokawa%2C%20Mozart%20-%20Momo%20Kodama%2C%20Mito%20Chamber%20Orchestra%2C%20Seiji%20Ozawa%20-%202021%2FHosokawa%2C%20Mozart.cue&metadata=false Jan 27 17:17:27 volumio volumio[1195]: info: URI /mnt/NAS/D47/ECM 2624 New Series - Hosokawa, Mozart - Momo Kodama, Mito Chamber Orchestra, Seiji Ozawa - 2021/Hosokawa, Mozart.cue Jan 27 17:17:27 volumio volumio[1195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 17:17:27 volumio volumio[1195]: Error: Unable to resolve or reject the same promise twice Jan 27 17:17:27 volumio volumio[1195]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 27 17:17:27 volumio volumio[1195]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 27 17:17:27 volumio volumio[1195]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 27 17:17:27 volumio volumio[1195]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 27 17:17:27 volumio volumio[1195]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 27 17:17:27 volumio volumio[1195]: at Socket.emit (node:events:514:28) Jan 27 17:17:27 volumio volumio[1195]: at addChunk (node:internal/streams/readable:343:12) Jan 27 17:17:27 volumio volumio[1195]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 27 17:17:27 volumio volumio[1195]: at Readable.push (node:internal/streams/readable:253:10) Jan 27 17:17:27 volumio volumio[1195]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 27 17:17:27 volumio volumio[1195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 27 17:17:28 volumio sudo[5781]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-27 17:16' Jan 27 17:17:28 volumio sudo[5781]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"