Dec 10 10:12:00 volumio volumio[1145]: info: Received Get System Version Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 10:12:00 volumio volumio[1145]: info: Received Get System Info Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 10:12:00 volumio volumio[1145]: info: Discovery: Getting this device information Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 10:12:00 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::volumioAddQueueItems Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::addQueueItems Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::addQueueItems Dec 10 10:12:00 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:00 volumio volumio[1145]: info: Adding Item to queue: music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim Dec 10 10:12:00 volumio volumio[1145]: info: Exploding uri music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim in service mpd Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F01%20-%20Corcovado%20(Quiet%20Nights%20Of%20Quiet%20Stars).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F02%20-%20Vivo%20Sonhando%20(Dreamer).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/02 - Vivo Sonhando (Dreamer).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F03%20-%20So%20Danco%20Samba.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/03 - So Danco Samba.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F04%20-%20Desafinado%20(Off%20Key).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/04 - Desafinado (Off Key).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F05%20-%20Aguas%20De%20Marco%20(Waters%20Of%20March).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/05 - Aguas De Marco (Waters Of March).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F06%20-%20O%20Grande%20Amor.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/06 - O Grande Amor.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F07%20-%20Agua%20De%20Beber.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/07 - Agua De Beber.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F08%20-%20Chovendo%20Na%20Roseiro%20(Double%20Rainbow).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/08 - Chovendo Na Roseiro (Double Rainbow).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F09%20-%20O%20Morro%20Nao%20Tem%20Vez.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/09 - O Morro Nao Tem Vez.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F10%20-%20Fascinating%20Rhythm.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/10 - Fascinating Rhythm.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F11%20-%20Insensatez%20(How%20Insensitive).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/11 - Insensatez (How Insensitive).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F12%20-%20Inutil%20Paisagem%20(Useless%20Landscape).flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/12 - Inutil Paisagem (Useless Landscape).flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F13%20-%20Favela.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/13 - Favela.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F14%20-%20Por%20Todo%20Minha%20Vida.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/14 - Por Todo Minha Vida.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F15%20-%20Triste.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/15 - Triste.flac Dec 10 10:12:00 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Antonio%20Carlos%20Jobim/Verve%20Jazz%20Masters%2013/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAntonio%20Carlos%20Jobim%2F(1993)%20Verve%20Jazz%20Masters%20-%20Antonio%20Carlos%20Jobim%2F16%20-%20Borzeguim.flac&metadata=false Dec 10 10:12:00 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/16 - Borzeguim.flac Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 10 10:12:00 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::play index 0 Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::stop Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:00 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 10 10:12:00 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:00 volumio volumio[1145]: verbose: ControllerMpd::clearAddPlayTracks USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac Dec 10 10:12:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 10:12:00 volumio volumio[1145]: info: sendMpdCommand stop took 29 milliseconds Dec 10 10:12:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: info: sendMpdCommand clear took 42 milliseconds Dec 10 10:12:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac" Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: error: updateQueue error: null Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 71ms Dec 10 10:12:00 volumio volumio[1145]: info: sendMpdCommand add "USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac" took 52 milliseconds Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 47ms Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 45ms Dec 10 10:12:00 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: info: Dec 10 10:12:00 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:00 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 69ms Dec 10 10:12:00 volumio volumio[1145]: info: sendMpdCommand play took 57 milliseconds Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 35ms Dec 10 10:12:00 volumio volumio[1145]: info: ------------------------------ 27ms Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 172 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: info: Dec 10 10:12:01 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 405 milliseconds Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 354 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand clearerror took 238 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 237 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 233 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 182 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand status took 153 milliseconds Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:01 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:01 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:01 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:01 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:01 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:01 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:01 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:01 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:01 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:01 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:01 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:01 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:01 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11. Dec 10 10:12:01 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:01 volumio volumio[1145]: info: ------------------------------ 857ms Dec 10 10:12:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:01 volumio upmpdcli[1986]: Could not open config: /tmp/upmpdcli.conf Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand clearerror took 528 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand clearerror took 527 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 384 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 383 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 382 milliseconds Dec 10 10:12:01 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 380 milliseconds Dec 10 10:12:02 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 379 milliseconds Dec 10 10:12:02 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:02 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 10:12:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 10:12:02 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:02 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:02 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:02 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:02 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:02 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:02 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:02 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:02 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:02 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:02 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:02 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:02 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:02 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:02 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:02 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:02 volumio volumio[1145]: info: ------------------------------ 1524ms Dec 10 10:12:02 volumio volumio[1145]: info: ------------------------------ 1523ms Dec 10 10:12:02 volumio volumio[1145]: info: ------------------------------ 1493ms Dec 10 10:12:02 volumio volumio[1145]: info: ------------------------------ 1269ms Dec 10 10:12:02 volumio volumio[1145]: info: ------------------------------ 1242ms Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPlay Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::play index undefined Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::startPlaybackTimer Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::clearAddPlayTracks USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand stop took 24 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:09 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:09 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:09 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand status took 38 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand clear took 38 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand status took 38 milliseconds Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac" Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:09 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 10:12:09 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::syncState stateService stop Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: info: No code Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: info: ------------------------------ 436ms Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:09 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:09 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:09 volumio volumio[1145]: error: updateQueue error: null Dec 10 10:12:09 volumio volumio[1145]: info: Dec 10 10:12:09 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:09 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand status took 479 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: ------------------------------ 478ms Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 474 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: sendMpdCommand add "USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac" took 474 milliseconds Dec 10 10:12:09 volumio volumio[1145]: info: ------------------------------ 91ms Dec 10 10:12:09 volumio volumio[1145]: info: ------------------------------ 68ms Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:09 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Dec 10 10:12:09 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 10 10:12:09 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::syncState stateService stop Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: info: No code Dec 10 10:12:09 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:09 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:09 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:09 volumio volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 10 10:12:10 volumio volumio[1145]: info: ------------------------------ 969ms Dec 10 10:12:10 volumio volumio[1145]: info: ------------------------------ 1037ms Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:10 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces system playlist update Dec 10 10:12:10 volumio volumio[1145]: info: Ignoring MPD Status Update Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:10 volumio volumio[1145]: info: ------------------------------ 851ms Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand play took 777 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:10 volumio volumio[1145]: info: ------------------------------ 294ms Dec 10 10:12:10 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:10 volumio volumio[1145]: info: ------------------------------ 316ms Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:10 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:10 volumio volumio[1145]: info: Dec 10 10:12:10 volumio volumio[1145]: ---------------------------- MPD announces state update: player Dec 10 10:12:10 volumio volumio[1145]: info: ControllerMpd::getState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand status took 146 milliseconds Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand status took 129 milliseconds Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand status took 106 milliseconds Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand status took 106 milliseconds Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::parseState Dec 10 10:12:10 volumio volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand clearerror took 223 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand clearerror took 219 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand clearerror took 209 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand clearerror took 207 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 78 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 78 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 10 10:12:10 volumio volumio[1145]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 10 10:12:11 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:11 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:11 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:11 volumio volumio[1145]: verbose: ControllerMpd::parseTrackInfo Dec 10 10:12:11 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:11 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:11 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:11 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:11 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:11 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:11 volumio volumio[1145]: info: ControllerMpd::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::servicePushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Corcovado (Quiet Nights Of Quiet Stars)","artist":"Antonio Carlos Jobim","album":"Verve Jazz Masters 13","uri":"USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1993) Verve Jazz Masters - Antonio Carlos Jobim/01 - Corcovado (Quiet Nights Of Quiet Stars).flac","trackType":"flac"} Dec 10 10:12:11 volumio volumio[1145]: verbose: CURRENT POSITION 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState stateService pause Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Dec 10 10:12:11 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:11 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:11 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:11 volumio volumio[1145]: info: ------------------------------ 848ms Dec 10 10:12:11 volumio volumio[1145]: info: ------------------------------ 829ms Dec 10 10:12:11 volumio volumio[1145]: info: ------------------------------ 772ms Dec 10 10:12:11 volumio volumio[1145]: info: ------------------------------ 754ms Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 10 10:12:14 volumio volumio[1145]: info: Received Get System Info Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 10 10:12:14 volumio volumio[1145]: info: Discovery: Getting this device information Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:14 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:15 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 10:12:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 10:12:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 12. Dec 10 10:12:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:17 volumio upmpdcli[2013]: Could not open config: /tmp/upmpdcli.conf Dec 10 10:12:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 10:12:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 10:12:19 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 10:12:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 10:12:19 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 10 10:12:19 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:19 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:19 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 10 10:12:19 volumio volumio[1145]: info: CoreStateMachine::pause Dec 10 10:12:19 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 10 10:12:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:19 volumio volumio[1145]: warn: Unable to locate the device USB2.0 High-Speed True HD Audio a reboot may be required. Dec 10 10:12:19 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:19 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 10:12:20 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: USB2.0 High-Speed True HD Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 10:12:20 volumio volumio[1145]: info: Disabling external Volume Control Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 10:12:20 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 10 10:12:20 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 10:12:20 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:20 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 10 10:12:20 volumio volumio[1145]: info: CoreStateMachine::pause Dec 10 10:12:20 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:20 volumio volumio[1145]: warn: Unable to locate the device USB2.0 High-Speed True HD Audio a reboot may be required. Dec 10 10:12:20 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:21 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:21 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 10:12:21 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: USB2.0 High-Speed True HD Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 10:12:21 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 10:12:21 volumio volumio[1145]: info: Disabling external Volume Control Dec 10 10:12:21 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 10:12:21 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:21 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:21 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:21 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:21 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:21 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:21 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:22 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 10:12:22 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 10 10:12:22 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 10 10:12:22 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:22 volumio sudo[2064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 10:12:22 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:22 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 10:12:22 volumio sudo[2066]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 10:12:22 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 10 10:12:22 volumio sudo[2066]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:22 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:22 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:22 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:22 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:22 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:23 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 10:12:23 volumio volumio[1145]: info: MPD Permissions set Dec 10 10:12:23 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 10:12:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 10:12:23 volumio systemd[1]: mpd.service: Consumed 4.876s CPU time. Dec 10 10:12:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 10:12:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 10:12:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 10:12:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 10:12:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio sudo[2082]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 10:12:23 volumio sudo[2082]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:23 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 10 10:12:23 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 10 10:12:23 volumio sudo[2082]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:24 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:24 volumio sudo[2092]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 10:12:24 volumio sudo[2092]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:24 volumio sudo[2092]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:24 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 10:12:24 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:24 volumio sudo[2095]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 10:12:24 volumio sudo[2095]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:24 volumio volumio[1145]: info: MPD Permissions set Dec 10 10:12:24 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:24 volumio volumio[1145]: info: Starting Shairport Sync Dec 10 10:12:24 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 10:12:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 10:12:24 volumio systemd[1]: mpd.service: Consumed 1.026s CPU time. Dec 10 10:12:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 10:12:24 volumio mpd_monitor.sh[2081]: MPD error: Connection reset by peer Dec 10 10:12:24 volumio mpd_monitor.sh[1572]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 10 10:12:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 10:12:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 10:12:24 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:24 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:24 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:24 volumio mpd_monitor.sh[2104]: mpd: no process found Dec 10 10:12:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 10:12:24 volumio sudo[2106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 10:12:24 volumio sudo[2106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 10:12:24 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 10 10:12:24 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 10 10:12:24 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:24 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 10:12:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 10:12:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 10:12:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 10:12:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 10:12:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 10:12:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 10:12:24 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 10:12:24 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 10:12:24 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:24 volumio systemd[1]: shairport-sync.service: Consumed 2.560s CPU time. Dec 10 10:12:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:25 volumio sudo[2106]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio sudo[2111]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 10:12:25 volumio sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 10:12:25 volumio sudo[2111]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:25 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 10:12:25 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:25 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 10:12:25 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:25 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 10 10:12:25 volumio volumio[1145]: info: CoreStateMachine::pause Dec 10 10:12:25 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 10 10:12:25 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:26 volumio volumio[1145]: warn: Unable to locate the device USB2.0 High-Speed True HD Audio a reboot may be required. Dec 10 10:12:26 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:26 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 10:12:27 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: USB2.0 High-Speed True HD Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 10:12:27 volumio volumio[1145]: info: Disabling external Volume Control Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 10:12:27 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 10 10:12:27 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:27 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:27 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:27 volumio volumio[1145]: info: Shairport-Sync Started Dec 10 10:12:27 volumio volumio[1145]: info: Starting Shairport Sync Dec 10 10:12:27 volumio volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 10 10:12:27 volumio volumio[1145]: info: Preparing to save Alsa Options, stopping services first Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Dec 10 10:12:27 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::volumioPause Dec 10 10:12:27 volumio volumio[1145]: info: CoreStateMachine::pause Dec 10 10:12:27 volumio volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Dec 10 10:12:27 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:28 volumio sudo[2151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 10:12:28 volumio volumio[1145]: warn: Unable to locate the device USB2.0 High-Speed True HD Audio a reboot may be required. Dec 10 10:12:28 volumio sudo[2151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:28 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 10:12:28 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 10:12:28 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:28 volumio systemd[1]: shairport-sync.service: Consumed 1.377s CPU time. Dec 10 10:12:28 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:28 volumio sudo[2151]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:28 volumio mpd[2131]: 2025-12-10T10:12:28 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 10:12:28 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:28 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:28 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 10:12:28 volumio mpd_monitor.sh[1572]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 10 10:12:28 volumio sudo[2095]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:28 volumio sudo[2066]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:28 volumio volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 10 10:12:28 volumio volumio[1145]: info: Updating Volume Controller Parameters: Device: 5 Name: USB2.0 High-Speed True HD Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 10 10:12:28 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 10 10:12:28 volumio volumio[1145]: info: Disabling external Volume Control Dec 10 10:12:28 volumio volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 10 10:12:29 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:29 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:29 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:29 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:29 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:29 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:29 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:29 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:29 volumio volumio[1145]: info: Shairport-Sync Started Dec 10 10:12:29 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:30 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:30 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:30 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:30 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:30 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:30 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 10 10:12:30 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:30 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 10:12:30 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 10 10:12:30 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 10 10:12:30 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:30 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:31 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 10:12:31 volumio sudo[2209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 10:12:31 volumio sudo[2209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:31 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Dec 10 10:12:31 volumio sudo[2211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 10:12:31 volumio sudo[2211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:31 volumio sudo[2209]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:31 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 10 10:12:31 volumio volumio[1145]: assert.ok(self.idling) Dec 10 10:12:31 volumio volumio[1145]: error: The expression evaluated to a falsy value: Dec 10 10:12:31 volumio volumio[1145]: assert.ok(self.idling) Dec 10 10:12:31 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 10:12:31 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 10 10:12:31 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:31 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 10 10:12:31 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 10:12:31 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 10:12:31 volumio systemd[1]: mpd.service: Consumed 3.533s CPU time. Dec 10 10:12:31 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 10:12:31 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 10:12:31 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 10:12:31 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 10:12:31 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 10:12:32 volumio sudo[2221]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 10:12:32 volumio sudo[2221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 10:12:32 volumio sudo[2221]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 10 10:12:32 volumio volumio[1145]: Invalid card number '5'. Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 10 10:12:32 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 13. Dec 10 10:12:32 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:32 volumio volumio[1145]: error: updateQueue error: null Dec 10 10:12:32 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:32 volumio volumio[1145]: info: MPD Permissions set Dec 10 10:12:32 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:32 volumio upmpdcli[2230]: Could not open config: /tmp/upmpdcli.conf Dec 10 10:12:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 10:12:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 10:12:32 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:32 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:32 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:32 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:33 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 10 10:12:33 volumio volumio[1145]: info: CoreStateMachine::getcurrentVolume Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:33 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:33 volumio sudo[2234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 10:12:33 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:33 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:33 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:33 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:33 volumio sudo[2236]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 10:12:33 volumio sudo[2236]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:33 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 10 10:12:33 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:33 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 10:12:33 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 10:12:33 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:33 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:34 volumio volumio[1145]: info: Asound.conf file unchanged, so no further update is needed Dec 10 10:12:34 volumio volumio[1145]: info: Output device has changed, restarting MPD Dec 10 10:12:34 volumio volumio[1145]: info: Output device has changed, restarting Shairport Sync Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:34 volumio sudo[2252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 10 10:12:34 volumio sudo[2252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:34 volumio volumio[1145]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 10 10:12:34 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:34 volumio sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 10 10:12:34 volumio sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:34 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:34 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:34 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:34 volumio sudo[2248]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:34 volumio volumio[1145]: info: CoreStateMachine::updateTrackBlock Dec 10 10:12:34 volumio volumio[1145]: info: CorePlayQueue::getTrackBlock Dec 10 10:12:34 volumio volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Dec 10 10:12:34 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 10 10:12:34 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 10 10:12:34 volumio systemd[1]: mpd.service: Consumed 2.866s CPU time. Dec 10 10:12:34 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 10 10:12:34 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 10 10:12:34 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 10 10:12:34 volumio volumio[1145]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number '5'. Dec 10 10:12:35 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 10 10:12:35 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 10 10:12:35 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:35 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:35 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:35 volumio volumio[1145]: info: MPD Permissions set Dec 10 10:12:35 volumio volumio[1145]: info: VolumeController:: Volume=undefined Mute =false Dec 10 10:12:35 volumio volumio[1145]: info: CoreStateMachine::pushState Dec 10 10:12:35 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:35 volumio volumio[1145]: info: Starting Shairport Sync Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio sudo[2261]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 10 10:12:35 volumio sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 10 10:12:35 volumio sudo[2261]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:35 volumio sudo[2263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 10:12:35 volumio sudo[2263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 10:12:35 volumio volumio[1145]: info: Starting Shairport Sync Dec 10 10:12:35 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 10:12:35 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 10:12:35 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:35 volumio systemd[1]: shairport-sync.service: Consumed 1.488s CPU time. Dec 10 10:12:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:36 volumio sudo[2273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 10 10:12:36 volumio sudo[2263]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:36 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:36 volumio volumio[1145]: info: Shairport-Sync Started Dec 10 10:12:36 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 10 10:12:36 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 10 10:12:36 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:36 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 10 10:12:36 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:36 volumio volumio[1145]: info: Shairport-Sync Started Dec 10 10:12:36 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:36 volumio volumio[1145]: info: CoreCommandRouter::volumioAddQueueItems Dec 10 10:12:36 volumio volumio[1145]: info: CoreStateMachine::addQueueItems Dec 10 10:12:36 volumio volumio[1145]: info: CorePlayQueue::addQueueItems Dec 10 10:12:36 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:36 volumio volumio[1145]: info: Adding Item to queue: music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1970) Night of Quiet Stars Dec 10 10:12:36 volumio volumio[1145]: info: Exploding uri music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Antonio Carlos Jobim/(1970) Night of Quiet Stars in service mpd Dec 10 10:12:38 volumio mpd[2265]: 2025-12-10T10:12:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 10 10:12:38 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 10 10:12:38 volumio sudo[2252]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:38 volumio sudo[2211]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:38 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:38 volumio mpd_monitor.sh[2308]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 10:12:38 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:38 volumio sudo[2236]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:39 volumio volumio[1145]: error: MPD error: The expression evaluated to a falsy value: Dec 10 10:12:39 volumio volumio[1145]: assert.ok(self.idling) Dec 10 10:12:39 volumio volumio[1145]: error: The expression evaluated to a falsy value: Dec 10 10:12:39 volumio volumio[1145]: assert.ok(self.idling) Dec 10 10:12:39 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 10 10:12:39 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 10 10:12:39 volumio volumio[1145]: error: updateQueue error: null Dec 10 10:12:41 volumio sudo[2327]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 10:12:41 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:42 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 10:12:42 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 10:12:42 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:42 volumio sudo[2329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 10 10:12:42 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 10 10:12:42 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:42 volumio mpd_monitor.sh[2331]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 10:12:42 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:42 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 10 10:12:42 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 10 10:12:42 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 10 10:12:42 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:42 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 10 10:12:42 volumio mpd_monitor.sh[2336]: MPD Monitor Service: Starting MPD Monitor Service Dec 10 10:12:42 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Dec 10 10:12:42 volumio volumio[1145]: info: Successfully started MPD Monitor Dec 10 10:12:44 volumio volumio[1145]: info: CoreCommandRouter::volumioClearQueue Dec 10 10:12:44 volumio volumio[1145]: info: CoreStateMachine::ClearQueue Dec 10 10:12:44 volumio volumio[1145]: info: CoreStateMachine::stop Dec 10 10:12:44 volumio volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 10:12:44 volumio volumio[1145]: info: CorePlayQueue::clearPlayQueue Dec 10 10:12:44 volumio volumio[1145]: info: CorePlayQueue::saveQueue Dec 10 10:12:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushState Dec 10 10:12:44 volumio volumio[1145]: info: CoreCommandRouter::volumioPushQueue Dec 10 10:12:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 14. Dec 10 10:12:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:12:47 volumio upmpdcli[2340]: Could not open config: /tmp/upmpdcli.conf Dec 10 10:12:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 10:12:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 10:12:52 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 10 10:12:52 volumio volumio[1145]: info: CURURI: music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter Dec 10 10:12:52 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:57 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:57 volumio volumio[1145]: info: CoreCommandRouter::volumioAddQueueItems Dec 10 10:12:57 volumio volumio[1145]: info: CoreStateMachine::addQueueItems Dec 10 10:12:57 volumio volumio[1145]: info: CorePlayQueue::addQueueItems Dec 10 10:12:57 volumio volumio[1145]: info: Preload queue cleared Dec 10 10:12:57 volumio volumio[1145]: info: Adding Item to queue: music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs Dec 10 10:12:57 volumio volumio[1145]: info: Exploding uri music-library/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs in service mpd Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F01%20-%20I%20-%20It%20May%20Not%20Always%20Be%20So.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/01 - I - It May Not Always Be So.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F02%20-%20II%20-%20We%20Met%20At%20The%20End%20Of%20The%20Party.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/02 - II - We Met At The End Of The Party.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F03%20-%20III%20-%20Child%2C%20Child.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/03 - III - Child, Child.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F04%20-%20IV%20-%20Twilight.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/04 - IV - Twilight.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F05%20-%20V%20-%20Because.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/05 - V - Because.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F06%20-%20VI%20-%20Dreams.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/06 - VI - Dreams.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2F07%20-%20VII%20-%20Did%20You%20Never%20Know.flac&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/07 - VII - Did You Never Know.flac Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2FLove%20Songs.cue&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/Love Songs.cue Dec 10 10:12:57 volumio volumio[1145]: info: ALBUMART /albumart?cacheid=419&web=Anne%20Sofie%20Von%20Otter%20%26%20Brad%20Mehldau/Love%20Songs/extralarge&path=%2Fmnt%2FUSB%2F2e129ca1-0349-4b37-8f18-d5f15cf6993c%2FJazz%2FAnne%20Sofie%20Von%20Otter%2FAnne%20Sofie%20Von%20Otter%20and%20Brad%20Mehldau%20-%20Love%20Songs%2FDisc%201%2FLove%20Songs.cue&metadata=false Dec 10 10:12:57 volumio volumio[1145]: info: URI /mnt/USB/2e129ca1-0349-4b37-8f18-d5f15cf6993c/Jazz/Anne Sofie Von Otter/Anne Sofie Von Otter and Brad Mehldau - Love Songs/Disc 1/Love Songs.cue Dec 10 10:12:57 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 10:12:57 volumio volumio[1145]: Error: Unable to resolve or reject the same promise twice Dec 10 10:12:57 volumio volumio[1145]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 10 10:12:57 volumio volumio[1145]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 10 10:12:57 volumio volumio[1145]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 10 10:12:57 volumio volumio[1145]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 10 10:12:57 volumio volumio[1145]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 10 10:12:57 volumio volumio[1145]: at Socket.emit (node:events:514:28) Dec 10 10:12:57 volumio volumio[1145]: at addChunk (node:internal/streams/readable:343:12) Dec 10 10:12:57 volumio volumio[1145]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 10 10:12:57 volumio volumio[1145]: at Readable.push (node:internal/streams/readable:253:10) Dec 10 10:12:57 volumio volumio[1145]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 10 10:12:57 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 10:13:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 15. Dec 10 10:13:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:13:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 10 10:13:03 volumio upmpdcli[2385]: Could not open config: /tmp/upmpdcli.conf Dec 10 10:13:03 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 10 10:13:03 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 10 10:13:05 volumio sudo[2388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-10 10:12' Dec 10 10:13:05 volumio sudo[2388]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"