Jan 25 17:50:01 volumio ntpd[875]: PROTO: 176.50.91.208 unlink local addr 192.168.1.74 -> Jan 25 17:50:29 volumio ntpd[875]: PROTO: 162.159.200.1 unlink local addr 192.168.1.74 -> Jan 25 17:50:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 17:50:42 volumio volumio[1245]: info: Received Get System Info Jan 25 17:50:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 25 17:50:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 25 17:50:42 volumio volumio[1245]: info: Discovery: Getting this device information Jan 25 17:50:42 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jan 25 17:50:42 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:50:42 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 25 17:50:55 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:50:55 volumio volumio[1245]: info: CURURI: music-library Jan 25 17:50:55 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:50:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:50:56 volumio volumio[1245]: info: CURURI: music-library/USB Jan 25 17:50:56 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:50:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:50:59 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE Jan 25 17:50:59 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:01 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO Jan 25 17:51:01 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:03 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:03 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO Jan 25 17:51:03 volumio ntpd[875]: PROTO: 92.241.18.100 unlink local addr 192.168.1.74 -> Jan 25 17:51:03 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:07 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:07 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia Jan 25 17:51:07 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:07 volumio volumio[1245]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 17:51:07 volumio volumio[1245]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 17:51:07 volumio volumio[1245]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 17:51:07 volumio volumio[1245]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 17:51:07 volumio volumio[1245]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 17:51:07 volumio volumio[1245]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 17:51:07 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 17:51:07 volumio volumio[1245]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 17:51:07 volumio volumio[1245]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 17:51:07 volumio volumio[1245]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 17:51:07 volumio volumio[1245]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 17:51:07 volumio volumio[1245]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 17:51:07 volumio volumio[1245]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 17:51:07 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 17:51:07 volumio volumio[1245]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 17:51:07 volumio volumio[1245]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 17:51:07 volumio volumio[1245]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 17:51:07 volumio volumio[1245]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 17:51:07 volumio volumio[1245]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 17:51:07 volumio volumio[1245]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 17:51:07 volumio volumio[1245]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 17:51:07 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 17:51:08 volumio volumio[1245]: An internal error occurred while serving an albumart. Details: NotFoundError: Not Found Jan 25 17:51:08 volumio volumio[1245]: at createHttpError (/volumio/node_modules/send/index.js:979:12) Jan 25 17:51:08 volumio volumio[1245]: at SendStream.error (/volumio/node_modules/send/index.js:270:31) Jan 25 17:51:08 volumio volumio[1245]: at SendStream.pipe (/volumio/node_modules/send/index.js:580:14) Jan 25 17:51:08 volumio volumio[1245]: at sendfile (/volumio/node_modules/express/lib/response.js:1139:8) Jan 25 17:51:08 volumio volumio[1245]: at ServerResponse.sendFile (/volumio/node_modules/express/lib/response.js:450:3) Jan 25 17:51:08 volumio volumio[1245]: at Promise._successFn (/volumio/app/plugins/miscellanea/albumart/albumart.js:444:9) Jan 25 17:51:08 volumio volumio[1245]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 25 17:51:08 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 25 17:51:22 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::ClearQueue Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::clearPlayQueue Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::saveQueue Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Jan 25 17:51:22 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:22 volumio volumio[1245]: info: Adding Item to queue: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia Jan 25 17:51:22 volumio volumio[1245]: info: Exploding uri music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia in service mpd Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F01%20Percusion%20Flamenca.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F02%20Barrio%20La%20Vina.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/02 Barrio La Vina.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F03%20Doblan%20Campanas.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/03 Doblan Campanas.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F04%20Farruca%20De%20Lucia.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/04 Farruca De Lucia.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F05%20Tientos%20De%20El%20Mentidero.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/05 Tientos De El Mentidero.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F06%20Farolillo%20De%20Feria.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/06 Farolillo De Feria.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F07%20De%20Madruga.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/07 De Madruga.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F08%20Cuando%20Canta%20El%20Gallo.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/08 Cuando Canta El Gallo.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F09%20Punta%20Del%20Faro.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/09 Punta Del Faro.flac Jan 25 17:51:22 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Paco%20de%20Luc%C3%ADa/El%20duende%20flamenco%20de%20Paco%20de%20Luc%C3%ADa/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FPaco%20de%20Lucia%2F1972%20-%20El%20duende%20flamenco%20de%20Paco%20de%20Lucia%2F10%20Canastera.flac&metadata=false Jan 25 17:51:22 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/10 Canastera.flac Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::saveQueue Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::updateTrackBlock Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrackBlock Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPlay Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::play index 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::play index undefined Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::startPlaybackTimer Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::clearAddPlayTracks USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand stop took 4 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 17:51:22 volumio upmpdcli[1546]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand clear took 10 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac" Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: error: updateQueue error: null Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 13ms Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand add "USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac" took 10 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 8ms Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 7ms Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jan 25 17:51:22 volumio volumio[1245]: info: Ignoring MPD Status Update Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 12ms Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand play took 7 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 7ms Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 5ms Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 22 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: info: Jan 25 17:51:22 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::getState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 42 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 39 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand clearerror took 28 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 27 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 19 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 94ms Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand status took 66 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand clearerror took 57 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand clearerror took 54 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 43 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 42 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 40 milliseconds Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 36 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseState Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 159ms Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 155ms Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 155ms Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 132ms Jan 25 17:51:22 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 60 milliseconds Jan 25 17:51:22 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jan 25 17:51:22 volumio volumio[1245]: info: ControllerMpd::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":225,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Percusion Flamenca","artist":"Paco de Lucía","album":"El duende flamenco de Paco de Lucía","uri":"USB/MUSIC_MOODE/LATINO/FLAMENCO/Paco de Lucia/1972 - El duende flamenco de Paco de Lucia/01 Percusion Flamenca.flac","trackType":"flac"} Jan 25 17:51:22 volumio volumio[1245]: verbose: CURRENT POSITION 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState stateService pause Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jan 25 17:51:22 volumio volumio[1245]: info: CoreStateMachine::pushState Jan 25 17:51:22 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jan 25 17:51:22 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jan 25 17:51:22 volumio volumio[1245]: info: ------------------------------ 145ms Jan 25 17:51:32 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:32 volumio volumio[1245]: info: CURURI: music-library Jan 25 17:51:32 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:34 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:34 volumio volumio[1245]: info: CURURI: music-library/USB Jan 25 17:51:34 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:34 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:34 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE Jan 25 17:51:34 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:36 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:36 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO Jan 25 17:51:36 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:37 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:37 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO Jan 25 17:51:37 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:39 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 25 17:51:39 volumio volumio[1245]: info: CURURI: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK Jan 25 17:51:39 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:43 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:43 volumio volumio[1245]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 17:51:43 volumio volumio[1245]: info: CoreStateMachine::ClearQueue Jan 25 17:51:43 volumio volumio[1245]: info: CoreStateMachine::stop Jan 25 17:51:43 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 17:51:43 volumio volumio[1245]: info: CorePlayQueue::clearPlayQueue Jan 25 17:51:43 volumio volumio[1245]: info: CorePlayQueue::saveQueue Jan 25 17:51:43 volumio volumio[1245]: info: CoreCommandRouter::volumioPushQueue Jan 25 17:51:43 volumio volumio[1245]: info: CoreStateMachine::addQueueItems Jan 25 17:51:43 volumio volumio[1245]: info: CorePlayQueue::addQueueItems Jan 25 17:51:43 volumio volumio[1245]: info: Preload queue cleared Jan 25 17:51:43 volumio volumio[1245]: info: Adding Item to queue: music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba Jan 25 17:51:43 volumio volumio[1245]: info: Exploding uri music-library/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba in service mpd Jan 25 17:51:43 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/The%20Rumba%20Foundation/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2009%20-%20Rumba%2FJesse%20Cook%20-%20The%20Rumba%20Foundation.cue&metadata=false Jan 25 17:51:43 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba/Jesse Cook - The Rumba Foundation.cue Jan 25 17:51:43 volumio volumio[1245]: info: ALBUMART /albumart?cacheid=856&web=Jesse%20Cook/The%20Rumba%20Foundation/extralarge&path=%2Fmnt%2FUSB%2FMUSIC_MOODE%2FLATINO%2FFLAMENCO%2FJESSE%20COOK%2F2009%20-%20Rumba%2FJesse%20Cook%20-%20The%20Rumba%20Foundation.cue&metadata=false Jan 25 17:51:43 volumio volumio[1245]: info: URI /mnt/USB/MUSIC_MOODE/LATINO/FLAMENCO/JESSE COOK/2009 - Rumba/Jesse Cook - The Rumba Foundation.cue Jan 25 17:51:43 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 17:51:43 volumio volumio[1245]: Error: Unable to resolve or reject the same promise twice Jan 25 17:51:43 volumio volumio[1245]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jan 25 17:51:43 volumio volumio[1245]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Jan 25 17:51:43 volumio volumio[1245]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Jan 25 17:51:43 volumio volumio[1245]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Jan 25 17:51:43 volumio volumio[1245]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Jan 25 17:51:43 volumio volumio[1245]: at Socket.emit (node:events:514:28) Jan 25 17:51:43 volumio volumio[1245]: at addChunk (node:internal/streams/readable:343:12) Jan 25 17:51:43 volumio volumio[1245]: at readableAddChunk (node:internal/streams/readable:312:11) Jan 25 17:51:43 volumio volumio[1245]: at Readable.push (node:internal/streams/readable:253:10) Jan 25 17:51:43 volumio volumio[1245]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jan 25 17:51:43 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 17:51:44 volumio sudo[2847]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 17:50' Jan 25 17:51:44 volumio sudo[2847]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"