Feb 06 19:14:03 volumio volumio[1404]: info: CorePlayQueue::getTrack 3 Feb 06 19:14:03 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:03 volumio volumio[1404]: info: Prefetching next song Feb 06 19:14:03 volumio volumio[1404]: info: DOING PREFETCH IN MPD Feb 06 19:14:03 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Music/Bryan Ferry - Dylanesque (Advance 2007)/05-bryan_ferry-all_i_really_wanna_do.mp3" Feb 06 19:14:03 volumio volumio[1404]: info: Feb 06 19:14:03 volumio volumio[1404]: ---------------------------- MPD announces system playlist update Feb 06 19:14:03 volumio volumio[1404]: info: Ignoring MPD Status Update Feb 06 19:14:03 volumio volumio[1404]: info: sendMpdCommand add "INTERNAL/Music/Bryan Ferry - Dylanesque (Advance 2007)/05-bryan_ferry-all_i_really_wanna_do.mp3" took 0 milliseconds Feb 06 19:14:03 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand consume 1 Feb 06 19:14:03 volumio volumio[1404]: info: ------------------------------ 1ms Feb 06 19:14:03 volumio volumio[1404]: info: sendMpdCommand consume 1 took 0 milliseconds Feb 06 19:14:07 volumio volumio[1404]: info: CoreStateMachine::startPlaybackTimer Feb 06 19:14:07 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:08 volumio volumio[1404]: info: CoreStateMachine::pushState Feb 06 19:14:08 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Feb 06 19:14:08 volumio volumio[1404]: info: FusionDsp - Volumio is playing Feb 06 19:14:08 volumio volumio[1404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Feb 06 19:14:08 volumio volumio[1404]: info: Feb 06 19:14:08 volumio volumio[1404]: ---------------------------- MPD announces system playlist update Feb 06 19:14:08 volumio volumio[1404]: info: Ignoring MPD Status Update Feb 06 19:14:08 volumio volumio[1404]: info: Feb 06 19:14:08 volumio volumio[1404]: ---------------------------- MPD announces state update: player Feb 06 19:14:08 volumio volumio[1404]: info: ControllerMpd::getState Feb 06 19:14:08 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand status Feb 06 19:14:08 volumio volumio[1404]: info: ------------------------------ 0ms Feb 06 19:14:08 volumio volumio[1404]: info: sendMpdCommand status took 1 milliseconds Feb 06 19:14:08 volumio volumio[1404]: verbose: ControllerMpd::parseState Feb 06 19:14:08 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 06 19:14:08 volumio volumio[1404]: info: sendMpdCommand playlistinfo took 0 milliseconds Feb 06 19:14:08 volumio volumio[1404]: verbose: ControllerMpd::parseTrackInfo Feb 06 19:14:08 volumio volumio[1404]: info: ControllerMpd::pushState Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::servicePushState Feb 06 19:14:08 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:08 volumio volumio[1404]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":150,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"All I Really Wanna Do","artist":"Bryan Ferry","album":"Dylanesque","uri":"INTERNAL/Music/Bryan Ferry - Dylanesque (Advance 2007)/05-bryan_ferry-all_i_really_wanna_do.mp3","trackType":"mp3"} Feb 06 19:14:08 volumio volumio[1404]: verbose: CURRENT POSITION 4 Feb 06 19:14:08 volumio volumio[1404]: info: CoreStateMachine::syncState stateService play Feb 06 19:14:08 volumio volumio[1404]: info: CoreStateMachine::syncState currentStatus play Feb 06 19:14:08 volumio volumio[1404]: info: Received an update from plugin. extracting info from payload Feb 06 19:14:08 volumio volumio[1404]: info: CoreStateMachine::pushState Feb 06 19:14:08 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Feb 06 19:14:08 volumio volumio[1404]: info: CoreStateMachine::pushState Feb 06 19:14:08 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:08 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Feb 06 19:14:08 volumio volumio[1404]: info: ------------------------------ 2ms Feb 06 19:14:08 volumio volumio[1404]: info: FusionDsp - Volumio is playing Feb 06 19:14:08 volumio volumio[1404]: info: FusionDsp - Volumio is playing Feb 06 19:14:08 volumio volumio[1404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Feb 06 19:14:08 volumio volumio[1404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Feb 06 19:14:25 volumio volumio[1404]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.181 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/17.14 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 7 Feb 06 19:14:25 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetVisibleSources Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Feb 06 19:14:26 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Feb 06 19:14:26 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 06 19:14:26 volumio volumio[1404]: info: Received Get System Info Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 06 19:14:26 volumio volumio[1404]: info: Discovery: Getting this device information Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Feb 06 19:14:26 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetState Feb 06 19:14:26 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:26 volumio volumio[1404]: info: Listing playlists Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::volumioGetQueue Feb 06 19:14:26 volumio volumio[1404]: info: CoreStateMachine::getQueue Feb 06 19:14:26 volumio volumio[1404]: info: CorePlayQueue::getQueue Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:26 volumio volumio[1404]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 06 19:14:29 volumio volumio[1404]: info: Preload queue cleared Feb 06 19:14:29 volumio volumio[1404]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::ClearQueue Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::stop Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::stPlaybackTimer Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::updateTrackBlock Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::getTrackBlock Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::pushState Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:29 volumio volumio[1404]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 06 19:14:29 volumio volumio[1404]: info: CoreCommandRouter::volumioPushState Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::serviceStop Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::getTrack 4 Feb 06 19:14:29 volumio volumio[1404]: info: CoreCommandRouter::serviceStop Feb 06 19:14:29 volumio volumio[1404]: info: ControllerMpd::stop Feb 06 19:14:29 volumio volumio[1404]: verbose: ControllerMpd::sendMpdCommand stop Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::clearPlayQueue Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::saveQueue Feb 06 19:14:29 volumio volumio[1404]: info: CoreCommandRouter::volumioPushQueue Feb 06 19:14:29 volumio volumio[1404]: info: CoreStateMachine::addQueueItems Feb 06 19:14:29 volumio volumio[1404]: info: CorePlayQueue::addQueueItems Feb 06 19:14:29 volumio volumio[1404]: info: Preload queue cleared Feb 06 19:14:29 volumio volumio[1404]: info: Adding Item to queue: music-library/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC Feb 06 19:14:29 volumio volumio[1404]: info: Exploding uri music-library/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC in service mpd Feb 06 19:14:29 volumio volumio[1404]: info: FusionDsp - Volumio is not playing Feb 06 19:14:29 volumio volumio[1404]: info: FusionDsp - Clipped samples monitor stopped Feb 06 19:14:29 volumio volumio[1404]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Feb 06 19:14:29 volumio volumio[1404]: info: Feb 06 19:14:29 volumio volumio[1404]: ---------------------------- MPD announces state update: player Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F01.%20Sensation.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/01. Sensation.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F02.%20Slave%20to%20Love.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/02. Slave to Love.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F03.%20Don't%20Stop%20the%20Dance.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/03. Don't Stop the Dance.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F04.%20A%20Waste%20Land.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/04. A Waste Land.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F05.%20Windswept.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/05. Windswept.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F06.%20The%20Chosen%20One.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/06. The Chosen One.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F07.%20Valentine.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/07. Valentine.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F08.%20Stone%20Woman.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/08. Stone Woman.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2F09.%20Boys%20and%20Girls.flac&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/09. Boys and Girls.flac Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%20Pressing%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2FBoys%20and%20Girls%20%5BWest%20German%20'Target'%20Pressing%5D%20(FLAC).cue&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/Boys and Girls [West German 'Target' Pressing] (FLAC).cue Feb 06 19:14:29 volumio volumio[1404]: info: ALBUMART /albumart?cacheid=82&web=Bryan%20Ferry/Boys%20and%20Girls%20%5BWest%20German%20'Target'%20Pressing%5D/extralarge&path=%2Fmnt%2FINTERNAL%2FMusic%2FBryan%20Ferry%20-1985-%20Boys%20and%20Girls%20FLAC%2FBoys%20and%20Girls%20%5BWest%20German%20'Target'%20Pressing%5D%20(FLAC).cue&metadata=false Feb 06 19:14:29 volumio volumio[1404]: info: URI /mnt/INTERNAL/Music/Bryan Ferry -1985- Boys and Girls FLAC/Boys and Girls [West German 'Target' Pressing] (FLAC).cue Feb 06 19:14:29 volumio volumio[1404]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:14:29 volumio volumio[1404]: Error: Unable to resolve or reject the same promise twice Feb 06 19:14:29 volumio volumio[1404]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 06 19:14:29 volumio volumio[1404]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Feb 06 19:14:29 volumio volumio[1404]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 06 19:14:29 volumio volumio[1404]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 06 19:14:29 volumio volumio[1404]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 06 19:14:29 volumio volumio[1404]: at Socket.emit (node:events:514:28) Feb 06 19:14:29 volumio volumio[1404]: at addChunk (node:internal/streams/readable:343:12) Feb 06 19:14:29 volumio volumio[1404]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 06 19:14:29 volumio volumio[1404]: at Readable.push (node:internal/streams/readable:253:10) Feb 06 19:14:29 volumio volumio[1404]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 06 19:14:29 volumio volumio[1404]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 06 19:14:29 volumio sudo[70666]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-06 19:13' Feb 06 19:14:29 volumio sudo[70666]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"