Apr 11 11:53:27 volumio go-librespot[1390]: time="2026-04-11T11:53:27+05:30" level=trace msg="sent dealer ping" Apr 11 11:53:27 volumio go-librespot[1390]: time="2026-04-11T11:53:27+05:30" level=trace msg="received dealer pong" Apr 11 11:53:57 volumio go-librespot[1390]: time="2026-04-11T11:53:57+05:30" level=trace msg="received accesspoint ping" Apr 11 11:53:57 volumio go-librespot[1390]: time="2026-04-11T11:53:57+05:30" level=trace msg="received accesspoint pong ack" Apr 11 11:53:57 volumio go-librespot[1390]: time="2026-04-11T11:53:57+05:30" level=trace msg="sent dealer ping" Apr 11 11:53:57 volumio go-librespot[1390]: time="2026-04-11T11:53:57+05:30" level=trace msg="received dealer pong" Apr 11 11:54:12 volumio ntpd[949]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 11:54:12 volumio ntpd[949]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Apr 11 11:54:12 volumio ntpd[949]: DNS: Pool taking: 192.46.211.253 Apr 11 11:54:12 volumio ntpd[949]: DNS: Pool taking: 143.110.241.116 Apr 11 11:54:12 volumio ntpd[949]: DNS: Pool taking: 162.159.200.123 Apr 11 11:54:12 volumio ntpd[949]: DNS: Pool skipping: 162.159.200.1 Apr 11 11:54:12 volumio ntpd[949]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Apr 11 11:54:13 volumio ntpd[949]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 11:54:13 volumio ntpd[949]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Apr 11 11:54:13 volumio ntpd[949]: DNS: Pool taking: 192.46.215.60 Apr 11 11:54:13 volumio ntpd[949]: DNS: Pool skipping: 192.46.211.253 Apr 11 11:54:13 volumio ntpd[949]: DNS: Pool skipping: 192.46.210.39 Apr 11 11:54:13 volumio ntpd[949]: DNS: Pool taking: 95.216.144.226 Apr 11 11:54:13 volumio ntpd[949]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Apr 11 11:54:16 volumio ntpd[949]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Apr 11 11:54:16 volumio ntpd[949]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 139.84.142.141 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool skipping: 162.159.200.123 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 103.136.36.100 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 172.236.180.15 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool skipping: 2402:1f00:8300:821::123 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 2401:c080:3400:2979:5400:5ff:fe35:2d0d Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 2600:3c16::2000:a0ff:fe85:2df0 Apr 11 11:54:16 volumio ntpd[949]: DNS: Pool taking: 2606:4700:f1::123 Apr 11 11:54:16 volumio ntpd[949]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Apr 11 11:54:26 volumio ntpd[949]: PROTO: 139.84.156.125 unlink local addr 192.168.1.100 -> Apr 11 11:54:27 volumio volumio[1101]: info: Preload queue cleared Apr 11 11:54:27 volumio volumio[1101]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::ClearQueue Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::stop Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::stPlaybackTimer Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::updateTrackBlock Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::getTrackBlock Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::pushState Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::getTrack 0 Apr 11 11:54:27 volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 11:54:27 volumio volumio[1101]: info: CoreCommandRouter::volumioPushState Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::serviceStop Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::getTrack 0 Apr 11 11:54:27 volumio volumio[1101]: info: CoreCommandRouter::serviceStop Apr 11 11:54:27 volumio volumio[1101]: info: ControllerMpd::stop Apr 11 11:54:27 volumio volumio[1101]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::clearPlayQueue Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::saveQueue Apr 11 11:54:27 volumio volumio[1101]: info: CoreCommandRouter::volumioPushQueue Apr 11 11:54:27 volumio volumio[1101]: info: CoreStateMachine::addQueueItems Apr 11 11:54:27 volumio volumio[1101]: info: CorePlayQueue::addQueueItems Apr 11 11:54:27 volumio volumio[1101]: info: Preload queue cleared Apr 11 11:54:27 volumio volumio[1101]: info: Adding Item to queue: music-library/NAS/Downloads/downloads/Europe 1986 'The Final Countdown' [2001 US Portrait Epic Legacy Sony EK 85757 expanded digital remaster] Apr 11 11:54:27 volumio volumio[1101]: info: Exploding uri music-library/NAS/Downloads/downloads/Europe 1986 'The Final Countdown' [2001 US Portrait Epic Legacy Sony EK 85757 expanded digital remaster] in service mpd Apr 11 11:54:27 volumio volumio[1101]: info: Apr 11 11:54:27 volumio volumio[1101]: ---------------------------- MPD announces state update: player Apr 11 11:54:27 volumio volumio[1101]: info: sendMpdCommand stop took 37 milliseconds Apr 11 11:54:27 volumio volumio[1101]: info: ControllerMpd::getState Apr 11 11:54:27 volumio volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:54:27 volumio volumio[1101]: info: Apr 11 11:54:27 volumio volumio[1101]: ---------------------------- MPD announces state update: player Apr 11 11:54:27 volumio volumio[1101]: info: ControllerMpd::getState Apr 11 11:54:27 volumio volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:54:27 volumio volumio[1101]: info: Apr 11 11:54:27 volumio volumio[1101]: ---------------------------- MPD announces state update: player Apr 11 11:54:27 volumio volumio[1101]: info: ControllerMpd::getState Apr 11 11:54:27 volumio volumio[1101]: verbose: ControllerMpd::sendMpdCommand status Apr 11 11:54:27 volumio volumio[1101]: info: ALBUMART /albumart?cacheid=576&web=Europe/The%20Final%20Countdown%20%5B2001%20US%20expanded%20digital%20remaster%5D/extralarge&path=%2Fmnt%2FNAS%2FDownloads%2Fdownloads%2FEurope%201986%20'The%20Final%20Countdown'%20%5B2001%20US%20Portrait%20Epic%20Legacy%20Sony%20EK%2085757%20expanded%20digital%20remaster%5D%2FEurope%20-%20The%20Final%20Countdown%20%5B2001%20US%20expanded%20digital%20remaster%5D.cue&metadata=false Apr 11 11:54:27 volumio volumio[1101]: info: URI /mnt/NAS/Downloads/downloads/Europe 1986 'The Final Countdown' [2001 US Portrait Epic Legacy Sony EK 85757 expanded digital remaster]/Europe - The Final Countdown [2001 US expanded digital remaster].cue Apr 11 11:54:27 volumio volumio[1101]: info: ALBUMART /albumart?cacheid=576&web=Europe/The%20Final%20Countdown%20%5B2001%20US%20expanded%20digital%20remaster%5D/extralarge&path=%2Fmnt%2FNAS%2FDownloads%2Fdownloads%2FEurope%201986%20'The%20Final%20Countdown'%20%5B2001%20US%20Portrait%20Epic%20Legacy%20Sony%20EK%2085757%20expanded%20digital%20remaster%5D%2FEurope%20-%20The%20Final%20Countdown%20%5B2001%20US%20expanded%20digital%20remaster%5D.cue&metadata=false Apr 11 11:54:27 volumio volumio[1101]: info: URI /mnt/NAS/Downloads/downloads/Europe 1986 'The Final Countdown' [2001 US Portrait Epic Legacy Sony EK 85757 expanded digital remaster]/Europe - The Final Countdown [2001 US expanded digital remaster].cue Apr 11 11:54:27 volumio volumio[1101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 11:54:27 volumio volumio[1101]: Error: Unable to resolve or reject the same promise twice Apr 11 11:54:27 volumio volumio[1101]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 11 11:54:27 volumio volumio[1101]: at /volumio/app/plugins/music_service/mpd/index.js:2582:21 Apr 11 11:54:27 volumio volumio[1101]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 11 11:54:27 volumio volumio[1101]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 11 11:54:27 volumio volumio[1101]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 11 11:54:27 volumio volumio[1101]: at Socket.emit (node:events:514:28) Apr 11 11:54:27 volumio volumio[1101]: at addChunk (node:internal/streams/readable:343:12) Apr 11 11:54:27 volumio volumio[1101]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 11 11:54:27 volumio volumio[1101]: at Readable.push (node:internal/streams/readable:253:10) Apr 11 11:54:27 volumio volumio[1101]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 11 11:54:27 volumio volumio[1101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 11:54:27 volumio go-librespot[1390]: time="2026-04-11T11:54:27+05:30" level=trace msg="sent dealer ping" Apr 11 11:54:27 volumio go-librespot[1390]: time="2026-04-11T11:54:27+05:30" level=trace msg="received dealer pong" Apr 11 11:54:28 volumio sudo[3794]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 11:53' Apr 11 11:54:28 volumio sudo[3794]: 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"