-- Logs begin at Thu 2025-01-16 17:07:29 CET, end at Thu 2025-01-16 22:06:58 CET. -- Jan 16 22:05:04 volumio volumio[948]: info: CoreCommandRouter::volumioPlay Jan 16 22:05:04 volumio volumio[948]: info: CoreStateMachine::play index undefined Jan 16 22:05:04 volumio volumio[948]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 22:05:04 volumio volumio[948]: info: CorePlayQueue::getTrack 1 Jan 16 22:05:04 volumio volumio[948]: info: CoreStateMachine::startPlaybackTimer Jan 16 22:05:04 volumio volumio[948]: info: CorePlayQueue::getTrack 1 Jan 16 22:05:04 volumio volumio[948]: info: [1737061504391] ControllerSpotify::clearAddPlayTrack Jan 16 22:05:04 volumio volumio[948]: info: Sending Spotify command with payload to local API: /player/play Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=debug msg="resolved context of track" uri="spotify:track:2sghtIUWplVFKBRIHsVmFu" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2sghtIUWplVFKBRIHsVmFu" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2sghtIUWplVFKBRIHsVmFu" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=trace msg="emitting websocket event: will_play" Jan 16 22:05:04 volumio volumio[948]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2sghtIUWplVFKBRIHsVmFu","play_origin":"go-librespot"}} Jan 16 22:05:04 volumio volumio[948]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2sghtIUWplVFKBRIHsVmFu","play_origin":"go-librespot"}} Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=debug msg="selected format OGG_VORBIS_320 (125b3bb1e61e847449d32191f365de45419e85f3)" uri="spotify:track:2sghtIUWplVFKBRIHsVmFu" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=debug msg="requested aes key for file 125b3bb1e61e847449d32191f365de45419e85f3, gid: 2sghtIUWplVFKBRIHsVmFu" Jan 16 22:05:04 volumio go-librespot[1048]: time="2025-01-16T22:05:04+01:00" level=error msg="failed handling status request" error="failed loading context: failed loading current track (load context): failed creating stream for spotify:track:2sghtIUWplVFKBRIHsVmFu: failed retrieving audio key: failed retrieving aes key with code 1" Jan 16 22:05:04 volumio volumio[948]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Jan 16 22:05:21 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Jan 16 22:05:21 volumio volumio[948]: info: In handleBrowseUri, curUri=spotify/myalbums Jan 16 22:05:21 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:25 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:29 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 16 22:05:29 volumio volumio[948]: info: CURURI: music-library Jan 16 22:05:29 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:32 volumio volumio[948]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 16 22:05:32 volumio volumio[948]: info: CURURI: music-library/USB Jan 16 22:05:32 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:34 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:34 volumio volumio[948]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 16 22:05:34 volumio volumio[948]: info: CoreStateMachine::ClearQueue Jan 16 22:05:34 volumio volumio[948]: info: CoreStateMachine::stop Jan 16 22:05:34 volumio volumio[948]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 16 22:05:34 volumio volumio[948]: info: CorePlayQueue::clearPlayQueue Jan 16 22:05:34 volumio volumio[948]: info: CorePlayQueue::saveQueue Jan 16 22:05:34 volumio volumio[948]: info: CoreCommandRouter::volumioPushQueue Jan 16 22:05:34 volumio volumio[948]: info: CoreStateMachine::addQueueItems Jan 16 22:05:34 volumio volumio[948]: info: CorePlayQueue::addQueueItems Jan 16 22:05:34 volumio volumio[948]: info: Preload queue cleared Jan 16 22:05:34 volumio volumio[948]: info: Adding Item to queue: music-library/USB/Elements Jan 16 22:05:34 volumio volumio[948]: info: Exploding uri music-library/USB/Elements in service mpd Jan 16 22:05:50 volumio volumio-remote-updater[3402]: [2025-01-16 22:05:50] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Jan 16 22:05:55 volumio volumio-remote-updater[3402]: [2025-01-16 22:05:55] [connect] Successful connection Jan 16 22:06:07 volumio volumio-remote-updater[3402]: [2025-01-16 22:06:07] [connect] Successful connection Jan 16 22:06:22 volumio volumio-remote-updater[3402]: [2025-01-16 22:06:22] [connect] Successful connection Jan 16 22:06:37 volumio volumio-remote-updater[3402]: [2025-01-16 22:06:37] [connect] Successful connection Jan 16 22:06:52 volumio volumio-remote-updater[3402]: [2025-01-16 22:06:52] [connect] Successful connection Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=The%20Savages/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F01.%20The%20Savages%20-%20Pain.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/01. The Savages - Pain.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F02.%20Asha%20Puthli%20-%20Sound%20Of%20Silence.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/02. Asha Puthli - Sound Of Silence.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F03.%20Asha%20Puthli%20-%20Sunny.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/03. Asha Puthli - Sunny.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F04.%20Asha%20Puthli%20-%20Fever.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/04. Asha Puthli - Fever.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Ornette%20Coleman/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F05.%20Ornette%20Coleman%20-%20What%20Reason%20Could%20I.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/05. Ornette Coleman - What Reason Could I.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Ornette%20Coleman/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F06.%20Ornette%20Coleman%20-%20All%20My%20Life.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/06. Ornette Coleman - All My Life.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Charlie%20Mariano/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F07.%20Charlie%20Mariano%20-%20Mirror.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/07. Charlie Mariano - Mirror.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F08.%20Asha%20Puthli%20-%20Right%20Down%20Here.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/08. Asha Puthli - Right Down Here.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F09.%20Asha%20Puthli%20-%20Lies.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/09. Asha Puthli - Lies.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F10.%20Asha%20Puthli%20-%20The%20Devil%20Is%20Loose.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/10. Asha Puthli - The Devil Is Loose.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F11.%20Asha%20Puthli%20-%20Space%20Talk.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/11. Asha Puthli - Space Talk.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F12.%20Asha%20Puthli%20-%20One%20Night%20Affair.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/12. Asha Puthli - One Night Affair.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F13.%20Asha%20Puthli%20-%20I'm%20Gonna%20Dance.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/13. Asha Puthli - I'm Gonna Dance.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F14.%20Asha%20Puthli%20-%20Music%20Machine%20(Dedication%20to%20Studio%2054).flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/14. Asha Puthli - Music Machine (Dedication to Studio 54).flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F15.%20Asha%20Puthli%20-%20Peek%20A%20Boo%20Boogie.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/15. Asha Puthli - Peek A Boo Boogie.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F16.%20Asha%20Puthli%20-%20Mister%20Moonlight.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/16. Asha Puthli - Mister Moonlight.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F17.%20Asha%20Puthli%20-%20Prism%20of%20the%20Sun%20(Song%20For%20Dieter).flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/17. Asha Puthli - Prism of the Sun (Song For Dieter).flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F18.%20Asha%20Puthli%20-%201001%20Nights%20of%20Love%20(Reprise).flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/18. Asha Puthli - 1001 Nights of Love (Reprise).flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F19.%20Asha%20Puthli%20-%20We're%20Gonna%20Bury%20the%20Rock%20with%20the%20Roll%20Tonight.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/19. Asha Puthli - We're Gonna Bury the Rock with the Roll Tonight.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=Asha%20Puthli/The%20Essential%20Asha%20Puthli/extralarge&path=%2Fmnt%2FUSB%2FElements%2FAsha%20Puthli%20-%20The%20Essential%20Asha%20Puthli%20(2022)%20FLAC%2F20.%20Asha%20Puthli%20-%20Chipko%20Chipko.flac&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/Asha Puthli - The Essential Asha Puthli (2022) FLAC/20. Asha Puthli - Chipko Chipko.flac Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2FDESKTOP-FREBJ4G%2FBackup%20Set%202024-12-02%20061659%2FBackup%20Files%202024-12-02%20061659%2FBackup%20files%2015.zip&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/DESKTOP-FREBJ4G/Backup Set 2024-12-02 061659/Backup Files 2024-12-02 061659/Backup files 15.zip Jan 16 22:06:57 volumio volumio[948]: info: ALBUMART /albumart?cacheid=255&web=//extralarge&path=%2Fmnt%2FUSB%2FElements%2FDESKTOP-FREBJ4G%2FBackup%20Set%202024-12-02%20061659%2FBackup%20Files%202024-12-02%20061659%2FBackup%20files%2015.zip&metadata=false Jan 16 22:06:57 volumio volumio[948]: info: URI /mnt/USB/Elements/DESKTOP-FREBJ4G/Backup Set 2024-12-02 061659/Backup Files 2024-12-02 061659/Backup files 15.zip Jan 16 22:06:57 volumio volumio[948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 22:06:57 volumio volumio[948]: Error: Unable to resolve or reject the same promise twice Jan 16 22:06:57 volumio volumio[948]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 16 22:06:57 volumio volumio[948]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 16 22:06:57 volumio volumio[948]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 16 22:06:57 volumio volumio[948]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 16 22:06:57 volumio volumio[948]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 16 22:06:57 volumio volumio[948]: at Socket.emit (events.js:400:28) Jan 16 22:06:57 volumio volumio[948]: at addChunk (internal/streams/readable.js:293:12) Jan 16 22:06:57 volumio volumio[948]: at readableAddChunk (internal/streams/readable.js:263:11) Jan 16 22:06:57 volumio volumio[948]: at Socket.Readable.push (internal/streams/readable.js:206:10) Jan 16 22:06:57 volumio volumio[948]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Jan 16 22:06:57 volumio volumio[948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 16 22:06:58 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-16 22:05 Jan 16 22:06:58 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"