-- Logs begin at Wed 2025-12-24 12:52:08 CET, end at Fri 2025-12-26 16:20:30 CET. -- Dec 26 16:19:00 volumio-5 volumio[955]: info: CoreStateMachine::startPlaybackTimer Dec 26 16:19:00 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: Dec 26 16:19:01 volumio-5 volumio[955]: ---------------------------- MPD announces system playlist update Dec 26 16:19:01 volumio-5 volumio[955]: info: Ignoring MPD Status Update Dec 26 16:19:01 volumio-5 volumio[955]: info: Dec 26 16:19:01 volumio-5 volumio[955]: ---------------------------- MPD announces state update: player Dec 26 16:19:01 volumio-5 volumio[955]: info: ControllerMpd::getState Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand status Dec 26 16:19:01 volumio-5 volumio[955]: info: Dec 26 16:19:01 volumio-5 volumio[955]: ---------------------------- MPD announces system playlist update Dec 26 16:19:01 volumio-5 volumio[955]: info: Ignoring MPD Status Update Dec 26 16:19:01 volumio-5 volumio[955]: info: Dec 26 16:19:01 volumio-5 volumio[955]: ---------------------------- MPD announces state update: player Dec 26 16:19:01 volumio-5 volumio[955]: info: ControllerMpd::getState Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand status Dec 26 16:19:01 volumio-5 volumio[955]: info: ------------------------------ 2ms Dec 26 16:19:01 volumio-5 volumio[955]: info: sendMpdCommand status took 1 milliseconds Dec 26 16:19:01 volumio-5 volumio[955]: info: ------------------------------ 1ms Dec 26 16:19:01 volumio-5 volumio[955]: info: sendMpdCommand status took 1 milliseconds Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::parseState Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::parseState Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 16:19:01 volumio-5 volumio[955]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 26 16:19:01 volumio-5 volumio[955]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::parseTrackInfo Dec 26 16:19:01 volumio-5 volumio[955]: verbose: ControllerMpd::parseTrackInfo Dec 26 16:19:01 volumio-5 volumio[955]: info: ControllerMpd::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::servicePushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"In This Room","artist":"Leslie Tucker","album":"Best Audiophile Voices III","uri":"USB/D6CA-621C/Best Audiophile Voices III DR0/12 Leslie Tucker - In This Room.mp3","trackType":"mp3"} Dec 26 16:19:01 volumio-5 volumio[955]: verbose: CURRENT POSITION 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::syncState stateService play Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::syncState currentStatus play Dec 26 16:19:01 volumio-5 volumio[955]: info: Received an update from plugin. extracting info from payload Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:19:01 volumio-5 volumio[955]: info: ControllerMpd::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::servicePushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"In This Room","artist":"Leslie Tucker","album":"Best Audiophile Voices III","uri":"USB/D6CA-621C/Best Audiophile Voices III DR0/12 Leslie Tucker - In This Room.mp3","trackType":"mp3"} Dec 26 16:19:01 volumio-5 volumio[955]: verbose: CURRENT POSITION 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::syncState stateService play Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::syncState currentStatus play Dec 26 16:19:01 volumio-5 volumio[955]: info: Received an update from plugin. extracting info from payload Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:19:01 volumio-5 volumio[955]: info: ------------------------------ 11ms Dec 26 16:19:01 volumio-5 volumio[955]: info: ------------------------------ 11ms Dec 26 16:19:01 volumio-5 volumio[955]: info: [ASDebug] CurState: play PrevState: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [ASDebug] CurState: play PrevState: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [ASDebug] CurState: play PrevState: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [ASDebug] CurState: play PrevState: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: play Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:19:01 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:01 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:19:01 volumio-5 volumio[955]: info: [ASDebug] CurState: play PrevState: play Dec 26 16:19:01 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: play Dec 26 16:19:19 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 16:19:19 volumio-5 volumio[955]: info: Received Get System Info Dec 26 16:19:19 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 16:19:19 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 16:19:19 volumio-5 volumio[955]: info: Discovery: Getting this device information Dec 26 16:19:19 volumio-5 volumio[955]: info: CoreCommandRouter::volumioGetState Dec 26 16:19:19 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:19 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 16:19:33 volumio-5 volumio[955]: info: CoreCommandRouter::volumioGetState Dec 26 16:19:33 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:19:35 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:19:35 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:19:35 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:19:35 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:19:35 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:19:35 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:19:57 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 16:19:57 volumio-5 volumio[955]: info: CURURI: music-library/USB/D6CA-621C/Cranberries OK Dec 26 16:19:57 volumio-5 volumio[955]: info: Preload queue cleared Dec 26 16:20:06 volumio-5 volumio[955]: info: CoreCommandRouter::volumioGetState Dec 26 16:20:06 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:20:08 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:20:08 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:20:08 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:20:08 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:20:08 volumio-5 volumio[955]: info: Executing endpoint metavolumio Dec 26 16:20:08 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 26 16:20:15 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 26 16:20:15 volumio-5 volumio[955]: info: CURURI: music-library/USB/D6CA-621C/Chopin Dec 26 16:20:15 volumio-5 volumio[955]: info: Preload queue cleared Dec 26 16:20:15 volumio-5 volumio[955]: info: Preloading song: music-library/USB/D6CA-621C/Chopin/Arthur Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1].(FLAC).flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Preloading song: music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Preloading song: music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Preloading song: music-library/USB/D6CA-621C/Chopin/Frederic Chopin - Etude in C minor (Op10 #12) - Revolutionary.flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Arthur Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1].(FLAC).flac in service mpd Dec 26 16:20:15 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=//mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2FArthur%20Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1%5D.(FLAC).flac&metadata=false Dec 26 16:20:15 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/Arthur Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1].(FLAC).flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac in service mpd Dec 26 16:20:15 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Lang%20Lang/Chopin%20-%20The%20Piano%20Concertos/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2FChopin%20-%20Lang%20Lang.flac&metadata=false Dec 26 16:20:15 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Using cached record of: music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:15 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Frederic Chopin - Etude in C minor (Op10 #12) - Revolutionary.flac in service mpd Dec 26 16:20:15 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Frederic%20Chopin%20/%E5%8F%A4%E5%85%B8%E5%AF%BC%E8%81%86%2006%20-%20%E5%99%A8%E4%B9%90%E7%8B%AC%E5%A5%8F%E6%9B%B2/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2FFrederic%20Chopin%20-%20Etude%20in%20C%20minor%20(Op10%20%2312)%20-%20Revolutionary.flac&metadata=false Dec 26 16:20:15 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/Frederic Chopin - Etude in C minor (Op10 #12) - Revolutionary.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Preload queue cleared Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::ClearQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::stop Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::stPlaybackTimer Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::updateTrackBlock Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrackBlock Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::pushState Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushState Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::serviceStop Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 11 Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::serviceStop Dec 26 16:20:30 volumio-5 volumio[955]: info: ControllerMpd::stop Dec 26 16:20:30 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::clearPlayQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::saveQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::addQueueItems Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::addQueueItems Dec 26 16:20:30 volumio-5 volumio[955]: info: Preload queue cleared Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Arthur Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1].(FLAC).flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Using cached record of: music-library/USB/D6CA-621C/Chopin/Arthur Rubinstein.-.The.Chopin.Collection.Nocturnes.Disc.1].(FLAC).flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac in service mpd Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac in service mpd Dec 26 16:20:30 volumio-5 volumio[955]: info: [ASDebug] CurState: stop PrevState: play Dec 26 16:20:30 volumio-5 volumio[955]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 26 16:20:30 volumio-5 volumio[955]: info: [GPIO_Control] Status changed: stop Dec 26 16:20:30 volumio-5 volumio[955]: info: [GPIO_Control] Handling event: musicStop Dec 26 16:20:30 volumio-5 volumio[955]: info: [GPIO_Control] *** musicStop *** Dec 26 16:20:30 volumio-5 volumio[955]: info: [GPIO_Control] Delaying: 10000ms Dec 26 16:20:30 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Lang%20Lang/Chopin%20-%20The%20Piano%20Concertos/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2FChopin%20-%20Lang%20Lang.flac&metadata=false Dec 26 16:20:30 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Dec 26 16:20:30 volumio-5 volumio[955]: ---------------------------- MPD announces state update: player Dec 26 16:20:30 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Lang%20Lang/Chopin%20-%20The%20Piano%20Concertos/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2FChopin%20-%20Lang%20Lang.flac&metadata=false Dec 26 16:20:30 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: sendMpdCommand stop took 8 milliseconds Dec 26 16:20:30 volumio-5 volumio[955]: info: ControllerMpd::getState Dec 26 16:20:30 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand status Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPushQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::saveQueue Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::updateTrackBlock Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrackBlock Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioPlay Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::play index 2 Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::addQueueItems Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::addQueueItems Dec 26 16:20:30 volumio-5 volumio[955]: info: Preload queue cleared Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Frederic Chopin - Etude in C minor (Op10 #12) - Revolutionary.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Using cached record of: music-library/USB/D6CA-621C/Chopin/Frederic Chopin - Etude in C minor (Op10 #12) - Revolutionary.flac Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/5_Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac').zip Dec 26 16:20:30 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/5_Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac').zip in service mpd Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Martha Argerich - Chopin The Complete Recordings On Deutsche Grammophon (2016) FLAC Dec 26 16:20:30 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Martha Argerich - Chopin The Complete Recordings On Deutsche Grammophon (2016) FLAC in service mpd Dec 26 16:20:30 volumio-5 volumio[955]: info: Adding Item to queue: music-library/USB/D6CA-621C/Chopin/Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac') Dec 26 16:20:30 volumio-5 volumio[955]: info: Exploding uri music-library/USB/D6CA-621C/Chopin/Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac') in service mpd Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::stop Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::play index undefined Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 2 Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreStateMachine::startPlaybackTimer Dec 26 16:20:30 volumio-5 volumio[955]: info: CorePlayQueue::getTrack 2 Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 16:20:30 volumio-5 volumio[955]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 26 16:20:30 volumio-5 volumio[955]: verbose: ControllerMpd::clearAddPlayTracks USB/D6CA-621C/Chopin/Chopin - Lang Lang.flac Dec 26 16:20:30 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 16:20:30 volumio-5 volumio[955]: info: Dec 26 16:20:30 volumio-5 volumio[955]: ---------------------------- MPD announces state update: player Dec 26 16:20:30 volumio-5 volumio[955]: info: ControllerMpd::getState Dec 26 16:20:30 volumio-5 volumio[955]: verbose: ControllerMpd::sendMpdCommand status Dec 26 16:20:30 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Maurizio%20Pollini/Chopin%20-%20Late%20Works%20opp.59-64/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2F5_Maurizio%20Pollini%20-%20%5BChopin%5D%20Late%20Works%20opp.59-64%20%5B24bit_96kHz%5D(rec_2015.05-2016.05)(HDtracks)(flac').zip&metadata=false Dec 26 16:20:30 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/5_Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac').zip Dec 26 16:20:30 volumio-5 volumio[955]: info: ALBUMART /albumart?cacheid=729&web=Maurizio%20Pollini/Chopin%20-%20Late%20Works%20opp.59-64/mega&path=%2Fmnt%2FUSB%2FD6CA-621C%2FChopin%2F5_Maurizio%20Pollini%20-%20%5BChopin%5D%20Late%20Works%20opp.59-64%20%5B24bit_96kHz%5D(rec_2015.05-2016.05)(HDtracks)(flac').zip&metadata=false Dec 26 16:20:30 volumio-5 volumio[955]: info: URI /mnt/USB/D6CA-621C/Chopin/5_Maurizio Pollini - [Chopin] Late Works opp.59-64 [24bit_96kHz](rec_2015.05-2016.05)(HDtracks)(flac').zip Dec 26 16:20:30 volumio-5 volumio[955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:20:30 volumio-5 volumio[955]: Error: Unable to resolve or reject the same promise twice Dec 26 16:20:30 volumio-5 volumio[955]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 26 16:20:30 volumio-5 volumio[955]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 26 16:20:30 volumio-5 volumio[955]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 26 16:20:30 volumio-5 volumio[955]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 26 16:20:30 volumio-5 volumio[955]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 26 16:20:30 volumio-5 volumio[955]: at Socket.emit (events.js:315:20) Dec 26 16:20:30 volumio-5 volumio[955]: at addChunk (internal/streams/readable.js:309:12) Dec 26 16:20:30 volumio-5 volumio[955]: at readableAddChunk (internal/streams/readable.js:280:11) Dec 26 16:20:30 volumio-5 volumio[955]: at Socket.Readable.push (internal/streams/readable.js:223:10) Dec 26 16:20:30 volumio-5 volumio[955]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 26 16:20:30 volumio-5 volumio[955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 16:20:30 volumio-5 sudo[17159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 16:19 Dec 26 16:20:30 volumio-5 sudo[17159]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"