Dec 20 19:17:04 volumio volumio[1207]: verbose: New Socket.io Connection to 192.168.1.12:3000 from 192.168.1.10 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 20 19:17:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 19:17:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 19:17:04 volumio volumio[1207]: info: Discovery: Getting this device information Dec 20 19:17:04 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 20 19:17:04 volumio volumio[1207]: info: CorePlayQueue::getTrack 24 Dec 20 19:17:04 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 19:17:35 volumio volumio[1207]: info: CorePlayQueue::getTrack 24 Dec 20 19:17:35 volumio volumio[1207]: info: CorePlayQueue::getTrack 25 Dec 20 19:17:41 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 3 ep 9 Dec 20 19:17:41 volumio kernel: xhci_hcd 0000:01:00.0: @0000000428d14f00 00000000 00000000 0e000000 030a8000 Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Dec 20 19:17:41 volumio volumio[1207]: info: Ignoring MPD Status Update Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces state update: player Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::getState Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Dec 20 19:17:41 volumio volumio[1207]: info: Ignoring MPD Status Update Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces state update: player Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::getState Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces system playlist update Dec 20 19:17:41 volumio volumio[1207]: info: Ignoring MPD Status Update Dec 20 19:17:41 volumio volumio[1207]: info: Dec 20 19:17:41 volumio volumio[1207]: ---------------------------- MPD announces state update: player Dec 20 19:17:41 volumio volumio[1207]: error: updateQueue error: null Dec 20 19:17:41 volumio volumio[1207]: error: updateQueue error: null Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::getState Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::sendMpdCommand status Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 3ms Dec 20 19:17:41 volumio volumio[1207]: info: sendMpdCommand status took 2 milliseconds Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 2ms Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::parseState Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 24 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 24 Dec 20 19:17:41 volumio volumio[1207]: 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 20 19:17:41 volumio volumio[1207]: verbose: CURRENT POSITION 24 Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus play Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::stPlaybackTimer Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 8ms Dec 20 19:17:41 volumio volumio[1207]: error: updateQueue error: null Dec 20 19:17:41 volumio volumio[1207]: info: sendMpdCommand status took 8 milliseconds Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 8ms Dec 20 19:17:41 volumio volumio[1207]: info: sendMpdCommand status took 7 milliseconds Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::parseState Dec 20 19:17:41 volumio volumio[1207]: verbose: ControllerMpd::parseState Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: 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 20 19:17:41 volumio volumio[1207]: verbose: CURRENT POSITION 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: No code Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: ControllerMpd::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::servicePushState Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: 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 20 19:17:41 volumio volumio[1207]: verbose: CURRENT POSITION 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState stateService stop Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::syncState currentStatus stop Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: No code Dec 20 19:17:41 volumio volumio[1207]: info: CoreStateMachine::pushState Dec 20 19:17:41 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:17:41 volumio volumio[1207]: info: CoreCommandRouter::volumioPushState Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 19ms Dec 20 19:17:41 volumio volumio[1207]: info: ------------------------------ 19ms Dec 20 19:18:28 volumio nmbd[1112]: [2025/12/20 19:18:28.211207, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 20 19:18:28 volumio nmbd[1112]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.12 for name WORKGROUP<1d>. Dec 20 19:18:28 volumio nmbd[1112]: This response was from IP 192.168.1.1, reporting an IP address of 192.168.1.1. Dec 20 19:18:30 volumio volumio[1207]: verbose: New Socket.io Connection to 192.168.1.12:3000 from 192.168.1.10 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 20 19:18:30 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 20 19:18:30 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 20 19:18:30 volumio volumio[1207]: info: Discovery: Getting this device information Dec 20 19:18:30 volumio volumio[1207]: info: CoreCommandRouter::volumioGetState Dec 20 19:18:30 volumio volumio[1207]: info: CorePlayQueue::getTrack 0 Dec 20 19:18:30 volumio volumio[1207]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 20 19:18:58 volumio volumio[1207]: info: Preload queue cleared Dec 20 19:18:58 volumio volumio[1207]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 20 19:18:58 volumio volumio[1207]: info: CoreStateMachine::ClearQueue Dec 20 19:18:58 volumio volumio[1207]: info: CoreStateMachine::stop Dec 20 19:18:58 volumio volumio[1207]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 20 19:18:58 volumio volumio[1207]: info: CorePlayQueue::clearPlayQueue Dec 20 19:18:58 volumio volumio[1207]: info: CorePlayQueue::saveQueue Dec 20 19:18:58 volumio volumio[1207]: info: CoreCommandRouter::volumioPushQueue Dec 20 19:18:58 volumio volumio[1207]: info: CoreStateMachine::addQueueItems Dec 20 19:18:58 volumio volumio[1207]: info: CorePlayQueue::addQueueItems Dec 20 19:18:58 volumio volumio[1207]: info: Preload queue cleared Dec 20 19:18:58 volumio volumio[1207]: info: Adding Item to queue: music-library/NAS/Music_1/Music_H/Music6/Borodin Quartet - Borodin String Quartets Nos. 1 & 2 Dec 20 19:18:58 volumio volumio[1207]: info: Exploding uri music-library/NAS/Music_1/Music_H/Music6/Borodin Quartet - Borodin String Quartets Nos. 1 & 2 in service mpd Dec 20 19:18:58 volumio volumio[1207]: info: ALBUMART /albumart?cacheid=941&web=//extralarge&path=%2Fmnt%2FNAS%2FMusic_1%2FMusic_H%2FMusic6%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202.ape&metadata=false Dec 20 19:18:58 volumio volumio[1207]: info: URI /mnt/NAS/Music_1/Music_H/Music6/Borodin Quartet - Borodin String Quartets Nos. 1 & 2/Borodin Quartet - Borodin String Quartets Nos. 1 & 2.ape Dec 20 19:18:58 volumio volumio[1207]: info: ALBUMART /albumart?cacheid=941&web=Borodin%20Quartet/Borodin%3A%20String%20Quartets%20Nos.%201%20%26%202/extralarge&path=%2Fmnt%2FNAS%2FMusic_1%2FMusic_H%2FMusic6%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202.cue&metadata=false Dec 20 19:18:58 volumio volumio[1207]: info: URI /mnt/NAS/Music_1/Music_H/Music6/Borodin Quartet - Borodin String Quartets Nos. 1 & 2/Borodin Quartet - Borodin String Quartets Nos. 1 & 2.cue Dec 20 19:18:58 volumio volumio[1207]: info: ALBUMART /albumart?cacheid=941&web=Borodin%20Quartet/Borodin%3A%20String%20Quartets%20Nos.%201%20%26%202/extralarge&path=%2Fmnt%2FNAS%2FMusic_1%2FMusic_H%2FMusic6%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202%2FBorodin%20Quartet%20-%20Borodin%20%20String%20Quartets%20Nos.%201%20%26%202.cue&metadata=false Dec 20 19:18:58 volumio volumio[1207]: info: URI /mnt/NAS/Music_1/Music_H/Music6/Borodin Quartet - Borodin String Quartets Nos. 1 & 2/Borodin Quartet - Borodin String Quartets Nos. 1 & 2.cue Dec 20 19:18:58 volumio volumio[1207]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 19:18:58 volumio volumio[1207]: Error: Unable to resolve or reject the same promise twice Dec 20 19:18:58 volumio volumio[1207]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 20 19:18:58 volumio volumio[1207]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 20 19:18:58 volumio volumio[1207]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 20 19:18:58 volumio volumio[1207]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 20 19:18:58 volumio volumio[1207]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 20 19:18:58 volumio volumio[1207]: at Socket.emit (node:events:514:28) Dec 20 19:18:58 volumio volumio[1207]: at addChunk (node:internal/streams/readable:343:12) Dec 20 19:18:58 volumio volumio[1207]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 20 19:18:58 volumio volumio[1207]: at Readable.push (node:internal/streams/readable:253:10) Dec 20 19:18:58 volumio volumio[1207]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 20 19:18:58 volumio volumio[1207]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 20 19:18:58 volumio sudo[19996]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-20 19:17' Dec 20 19:18:58 volumio sudo[19996]: 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"