-- Logs begin at Thu 2024-10-10 17:00:13 CST, end at Fri 2024-10-11 03:00:08 CST. -- Oct 11 02:59:02 volumio volumio[6729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 11 02:59:02 volumio volumio[6729]: info: CURURI: music-library/NAS/czhsh/贺年歌 Oct 11 02:59:02 volumio volumio[6729]: info: Preload queue cleared Oct 11 02:59:06 volumio volumio[6729]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 11 02:59:06 volumio volumio[6729]: info: CURURI: music-library/NAS/czhsh/英文 Oct 11 02:59:06 volumio volumio[6729]: info: Preload queue cleared Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Celine Dion-爱的力量.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Michael Jackson-You Are Not Alone.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Ricky Martin-La Copa De La Vida.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/TikTok-Careless Whisper.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Gloria Estefan-Reach.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Mariah Carey-I Still Believe.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Koreana-Hand In Hand.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Mariah Carey-Hero.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Carpenters-Yesterday Once More.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Michael Jackson-Billie Jean.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Laura Branigon-Self Control.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Whitney Houston-I Will Always Love You.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Celine Dion-The Power Of The Dream(Atlanta 1996 Olympics).flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Grant Miller-Colder Than Ice.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Michael Jackson-We Are The World(Demo).flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Richard Marx-Right Here Waiting(Single Edit).flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Michael Jackson-Beat It.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Edoardo Bennato&Gianna Nannini-Un'estate italiana [Stadio Version].flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Mariah Carey-Without You.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Celine Dion-我心永恒.flac Oct 11 02:59:06 volumio volumio[6729]: info: Preloading song: music-library/NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Celine Dion-爱的力量.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion//extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-%E7%88%B1%E7%9A%84%E5%8A%9B%E9%87%8F.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-爱的力量.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Michael Jackson-You Are Not Alone.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/1996%20Grammy%20Nominees/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-You%20Are%20Not%20Alone.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-You Are Not Alone.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Ricky Martin-La Copa De La Vida.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Ricky%20Martin/1998%E5%B9%B4%E6%B3%95%E5%9B%BD%E4%B8%96%E7%95%8C%E6%9D%AF%E5%AE%98%E6%96%B9%E4%B8%93%E8%BE%91/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FRicky%20Martin-La%20Copa%20De%20La%20Vida.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Ricky Martin-La Copa De La Vida.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/TikTok-Careless Whisper.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=TikTok/Careless%20Whisper/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FTikTok-Careless%20Whisper.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/TikTok-Careless Whisper.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Gloria Estefan-Reach.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Gloria%20Estefan/Destiny/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FGloria%20Estefan-Reach.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Gloria Estefan-Reach.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Mariah Carey-I Still Believe.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Greatest%20Hits/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-I%20Still%20Believe.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-I Still Believe.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Koreana-Hand In Hand.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Koreana/Hand%20In%20Hand%5B'88%20Seoul%20Olympic%20Song%5D/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FKoreana-Hand%20In%20Hand.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Koreana-Hand In Hand.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Mariah Carey-Hero.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Hero/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-Hero.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-Hero.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Carpenters-Yesterday Once More.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Carpenters/Now%20%26%20Then/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCarpenters-Yesterday%20Once%20More.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Carpenters-Yesterday Once More.flac Oct 11 02:59:06 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Michael Jackson-Billie Jean.flac in service mpd Oct 11 02:59:06 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/Number%201's%3A%20The%20Jacksons%20Story/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-Billie%20Jean.flac&metadata=false Oct 11 02:59:06 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-Billie Jean.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Laura Branigon-Self Control.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Laura%20Branigon/Rhino%20Hi-Five%3A%20Laura%20Branigan/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FLaura%20Branigon-Self%20Control.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Laura Branigon-Self Control.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Whitney Houston-I Will Always Love You.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Whitney%20Houston/The%20Bodyguard%20-%20Original%20Soundtrack%20Album/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FWhitney%20Houston-I%20Will%20Always%20Love%20You.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Whitney Houston-I Will Always Love You.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Celine Dion-The Power Of The Dream(Atlanta 1996 Olympics).flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion/The%20Collector's%20Series%20Volume%20One/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-The%20Power%20Of%20The%20Dream(Atlanta%201996%20Olympics).flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-The Power Of The Dream(Atlanta 1996 Olympics).flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Grant Miller-Colder Than Ice.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Grant%20Miller/The%20Maxi%20-%20Singles%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FGrant%20Miller-Colder%20Than%20Ice.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Grant Miller-Colder Than Ice.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Michael Jackson-We Are The World(Demo).flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/The%20Ultimate%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-We%20Are%20The%20World(Demo).flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-We Are The World(Demo).flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Richard Marx-Right Here Waiting(Single Edit).flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Richard%20Marx/The%20Ultimate%20Love%20Songs%20Playlist/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FRichard%20Marx-Right%20Here%20Waiting(Single%20Edit).flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Richard Marx-Right Here Waiting(Single Edit).flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Michael Jackson-Beat It.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/Thriller/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-Beat%20It.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-Beat It.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Edoardo Bennato&Gianna Nannini-Un'estate italiana [Stadio Version].flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Edoardo%20Bennato%26Gianna%20Nannini/Un'estate%20italiana/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FEdoardo%20Bennato%26Gianna%20Nannini-Un'estate%20italiana%20%5BStadio%20Version%5D.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Edoardo Bennato&Gianna Nannini-Un'estate italiana [Stadio Version].flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Mariah Carey-Without You.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Without%20You/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-Without%20You.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-Without You.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Celine Dion-我心永恒.flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion/%E6%AC%A7%E7%BE%8E%E7%BB%8F%E5%85%B8%E6%83%85%E6%AD%8C/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-%E6%88%91%E5%BF%83%E6%B0%B8%E6%81%92.flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-我心永恒.flac Oct 11 02:59:07 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac in service mpd Oct 11 02:59:07 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Bertie%20Higgins/%E8%A5%BF%E6%B4%8B%E7%B6%93%E5%85%B8%E6%83%85%E6%AD%8C%20%E7%AC%AC%E4%B8%80%E8%BC%AF/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FBertie%20Higgins-Casablanca(%E5%8D%A1%E8%96%A9%E5%B8%83%E8%98%AD%E5%8A%A0%20%E6%AF%94%E8%8E%89%E3%80%81'%E7%9B%BC%E4%BD%A0%E5%9B%9E%E9%A6%96%E3%80%81'%E5%8E%9F%E6%9B%B2%20%E4%BD%8E%E6%B2%89%E5%97%93%E9%9F%B3%20%E6%97%8B%E5%BE%8B%E5%84%AA%E7%BE%8E).flac&metadata=false Oct 11 02:59:07 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac Oct 11 02:59:12 volumio volumio[6729]: info: Preload queue cleared Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::ClearQueue Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::stop Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::clearPlayQueue Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::saveQueue Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushQueue Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::addQueueItems Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::addQueueItems Oct 11 02:59:12 volumio volumio[6729]: info: Preload queue cleared Oct 11 02:59:12 volumio volumio[6729]: info: Adding Item to queue: music-library/NAS/czhsh/英文 Oct 11 02:59:12 volumio volumio[6729]: info: Exploding uri music-library/NAS/czhsh/英文 in service mpd Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Bertie%20Higgins/%E8%A5%BF%E6%B4%8B%E7%B6%93%E5%85%B8%E6%83%85%E6%AD%8C%20%E7%AC%AC%E4%B8%80%E8%BC%AF/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FBertie%20Higgins-Casablanca(%E5%8D%A1%E8%96%A9%E5%B8%83%E8%98%AD%E5%8A%A0%20%E6%AF%94%E8%8E%89%E3%80%81'%E7%9B%BC%E4%BD%A0%E5%9B%9E%E9%A6%96%E3%80%81'%E5%8E%9F%E6%9B%B2%20%E4%BD%8E%E6%B2%89%E5%97%93%E9%9F%B3%20%E6%97%8B%E5%BE%8B%E5%84%AA%E7%BE%8E).flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Carpenters/Now%20%26%20Then/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCarpenters-Yesterday%20Once%20More.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Carpenters-Yesterday Once More.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion/The%20Collector's%20Series%20Volume%20One/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-The%20Power%20Of%20The%20Dream(Atlanta%201996%20Olympics).flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-The Power Of The Dream(Atlanta 1996 Olympics).flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion/%E6%AC%A7%E7%BE%8E%E7%BB%8F%E5%85%B8%E6%83%85%E6%AD%8C/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-%E6%88%91%E5%BF%83%E6%B0%B8%E6%81%92.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-我心永恒.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Celine%20Dion//extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FCeline%20Dion-%E7%88%B1%E7%9A%84%E5%8A%9B%E9%87%8F.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Celine Dion-爱的力量.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Edoardo%20Bennato%26Gianna%20Nannini/Un'estate%20italiana/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FEdoardo%20Bennato%26Gianna%20Nannini-Un'estate%20italiana%20%5BStadio%20Version%5D.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Edoardo Bennato&Gianna Nannini-Un'estate italiana [Stadio Version].flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Gloria%20Estefan/Destiny/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FGloria%20Estefan-Reach.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Gloria Estefan-Reach.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Grant%20Miller/The%20Maxi%20-%20Singles%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FGrant%20Miller-Colder%20Than%20Ice.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Grant Miller-Colder Than Ice.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Koreana/Hand%20In%20Hand%5B'88%20Seoul%20Olympic%20Song%5D/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FKoreana-Hand%20In%20Hand.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Koreana-Hand In Hand.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Laura%20Branigon/Rhino%20Hi-Five%3A%20Laura%20Branigan/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FLaura%20Branigon-Self%20Control.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Laura Branigon-Self Control.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Hero/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-Hero.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-Hero.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Greatest%20Hits/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-I%20Still%20Believe.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-I Still Believe.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Mariah%20Carey/Without%20You/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMariah%20Carey-Without%20You.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Mariah Carey-Without You.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/Thriller/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-Beat%20It.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-Beat It.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/Number%201's%3A%20The%20Jacksons%20Story/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-Billie%20Jean.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-Billie Jean.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/The%20Ultimate%20Collection/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-We%20Are%20The%20World(Demo).flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-We Are The World(Demo).flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Michael%20Jackson/1996%20Grammy%20Nominees/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FMichael%20Jackson-You%20Are%20Not%20Alone.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Michael Jackson-You Are Not Alone.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Richard%20Marx/The%20Ultimate%20Love%20Songs%20Playlist/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FRichard%20Marx-Right%20Here%20Waiting(Single%20Edit).flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Richard Marx-Right Here Waiting(Single Edit).flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Ricky%20Martin/1998%E5%B9%B4%E6%B3%95%E5%9B%BD%E4%B8%96%E7%95%8C%E6%9D%AF%E5%AE%98%E6%96%B9%E4%B8%93%E8%BE%91/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FRicky%20Martin-La%20Copa%20De%20La%20Vida.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Ricky Martin-La Copa De La Vida.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=TikTok/Careless%20Whisper/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FTikTok-Careless%20Whisper.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/TikTok-Careless Whisper.flac Oct 11 02:59:12 volumio volumio[6729]: info: ALBUMART /albumart?cacheid=745&web=Whitney%20Houston/The%20Bodyguard%20-%20Original%20Soundtrack%20Album/extralarge&path=%2Fmnt%2FNAS%2Fczhsh%2F%E8%8B%B1%E6%96%87%2FWhitney%20Houston-I%20Will%20Always%20Love%20You.flac&metadata=false Oct 11 02:59:12 volumio volumio[6729]: info: URI /mnt/NAS/czhsh/英文/Whitney Houston-I Will Always Love You.flac Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushQueue Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::saveQueue Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPlay Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::play index 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::stop Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::play index undefined Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::startPlaybackTimer Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::clearAddPlayTracks NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand stop Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand stop took 2 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand clear Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand clear took 2 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand add "NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac" Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: error: updateQueue error: null Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 8ms Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand add "NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac" took 6 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 7ms Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 6ms Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand play Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces system playlist update Oct 11 02:59:12 volumio volumio[6729]: info: Ignoring MPD Status Update Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 6ms Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand play took 4 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 3ms Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 2ms Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 10 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 11 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 10 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"805 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus stop Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 22ms Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: Oct 11 02:59:12 volumio volumio[6729]: ---------------------------- MPD announces state update: player Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::getState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand status Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 11 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 3 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand status took 2 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseState Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus play Oct 11 02:59:12 volumio volumio[6729]: info: Received an update from plugin. extracting info from payload Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus play Oct 11 02:59:12 volumio volumio[6729]: info: Received an update from plugin. extracting info from payload Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 63ms Oct 11 02:59:12 volumio volumio[6729]: info: ------------------------------ 61ms Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 11 02:59:12 volumio volumio[6729]: info: sendMpdCommand playlistinfo took 39 milliseconds Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: verbose: ControllerMpd::parseTrackInfo Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus play Oct 11 02:59:12 volumio volumio[6729]: info: Received an update from plugin. extracting info from payload Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus play Oct 11 02:59:12 volumio volumio[6729]: info: Received an update from plugin. extracting info from payload Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: ControllerMpd::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::servicePushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: verbose: STATE SERVICE {"status":"play","position":0,"seek":842,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"791 Kbps","isStreaming":false,"title":"Casablanca(卡薩布蘭加 比莉\\\"盼你回首\\\"原曲 低沉嗓音 旋律優美)","artist":"Bertie Higgins","album":"西洋經典情歌 第一輯","uri":"NAS/czhsh/英文/Bertie Higgins-Casablanca(卡薩布蘭加 比莉、'盼你回首、'原曲 低沉嗓音 旋律優美).flac","trackType":"flac"} Oct 11 02:59:12 volumio volumio[6729]: verbose: CURRENT POSITION 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState stateService play Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::syncState currentStatus play Oct 11 02:59:12 volumio volumio[6729]: info: Received an update from plugin. extracting info from payload Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:12 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:12 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:12 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:13 volumio volumio[6729]: info: ------------------------------ 103ms Oct 11 02:59:13 volumio volumio[6729]: info: ------------------------------ 95ms Oct 11 02:59:13 volumio volumio[6729]: info: ------------------------------ 95ms Oct 11 02:59:16 volumio volumio[6729]: info: VolumeController::SetAlsaVolume23 Oct 11 02:59:16 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:16 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:16 volumio volumio[6729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:16 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:16 volumio volumio[6729]: info: VolumeController::SetAlsaVolume10 Oct 11 02:59:16 volumio volumio[6729]: info: CoreStateMachine::pushState Oct 11 02:59:16 volumio volumio[6729]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:16 volumio volumio[6729]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:16 volumio volumio[6729]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:26 volumio volumio[6729]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:26 volumio volumio[6729]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 02:59:26 volumio volumio[6729]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:26 volumio volumio[6729]: errno: -111, Oct 11 02:59:26 volumio volumio[6729]: code: 'ECONNREFUSED', Oct 11 02:59:26 volumio volumio[6729]: syscall: 'connect', Oct 11 02:59:26 volumio volumio[6729]: address: '199.59.148.7', Oct 11 02:59:26 volumio volumio[6729]: port: 443 Oct 11 02:59:26 volumio volumio[6729]: } Oct 11 02:59:26 volumio volumio[6729]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:26 volumio sudo[7056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:26 volumio sudo[7056]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:26 volumio sudo[7056]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:27 volumio volumio-remote-updater[733]: [2024-10-11 02:59:27] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 11 02:59:27 volumio volumio-remote-updater[733]: [2024-10-11 02:59:27] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 11 02:59:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 02:59:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 02:59:27 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:27 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:28 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 02:59:28 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Oct 11 02:59:28 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 02:59:28 volumio systemd[1]: Started Volumio Backend Module. Oct 11 02:59:28 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:28 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:29 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:29 volumio volumio[7114]: info: ----- Volumio3 ---- Oct 11 02:59:29 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:29 volumio volumio[7114]: info: ----- System startup ---- Oct 11 02:59:29 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:29 volumio volumio[7114]: info: MYVOLUMIO Environment detected Oct 11 02:59:29 volumio volumio[7114]: info: Plugin folders cleanup Oct 11 02:59:29 volumio volumio[7114]: info: Scanning into folder /volumio/app/plugins/ Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category audio_interface Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category miscellanea Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category music_service Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category plugins.json Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category system_controller Oct 11 02:59:29 volumio volumio[7114]: info: Scanning category user_interface Oct 11 02:59:29 volumio volumio[7114]: info: Scanning into folder /data/plugins/ Oct 11 02:59:29 volumio volumio[7114]: info: Plugin folders cleanup completed Oct 11 02:59:29 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:29 volumio volumio[7114]: info: ----- Core plugins startup ---- Oct 11 02:59:29 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:29 volumio volumio[7114]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 02:59:29 volumio volumio[7114]: info: Adding plugin upnp to MyMusic Plugins Oct 11 02:59:29 volumio volumio[7114]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 02:59:29 volumio volumio[7114]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 02:59:29 volumio volumio[7114]: info: Loading plugins from folder /data/plugins/ Oct 11 02:59:29 volumio volumio[7114]: info: Loading plugin "system"... Oct 11 02:59:29 volumio volumio[7114]: info: Loading plugin "appearance"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "network"... Oct 11 02:59:30 volumio volumio[7114]: info: Refreshing Cached IP Addresses Oct 11 02:59:30 volumio sudo[7142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "services"... Oct 11 02:59:30 volumio sudo[7142]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "alsa_controller"... Oct 11 02:59:30 volumio sudo[7142]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:30 volumio sudo[7144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 02:59:30 volumio sudo[7144]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:30 volumio sudo[7144]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:30 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "wizard"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "networkfs"... Oct 11 02:59:30 volumio volumio[7114]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 02:59:30 volumio volumio[7114]: info: Starting Udev Watcher for removable devices Oct 11 02:59:30 volumio volumio[7114]: info: Ignoring mount for partition: boot Oct 11 02:59:30 volumio volumio[7114]: info: Ignoring mount for partition: volumio Oct 11 02:59:30 volumio volumio[7114]: info: Ignoring mount for partition: volumio_data Oct 11 02:59:30 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "volumio_command_line_client"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "upnp"... Oct 11 02:59:30 volumio volumio[7114]: info: [1728586770215] Starting Upmpd Daemon Oct 11 02:59:30 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "my_music"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "mpd"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "upnp_browser"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "alarm-clock"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "airplay_emulation"... Oct 11 02:59:30 volumio volumio[7114]: info: Starting Shairport Sync Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "last_100"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "webradio"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "i2s_dacs"... Oct 11 02:59:30 volumio volumio[7114]: info: I2S DAC not set, start Auto-detection Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "volumiodiscovery"... Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:30 volumio node[7114]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** For more information see Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:30 volumio volumio[7114]: *** WARNING *** For more information see Oct 11 02:59:30 volumio node[7114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:30 volumio node[7114]: *** WARNING *** For more information see Oct 11 02:59:30 volumio node[7114]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:30 volumio node[7114]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:30 volumio node[7114]: *** WARNING *** For more information see Oct 11 02:59:30 volumio volumio[7114]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 02:59:30 volumio volumio[7114]: info: Discovery: Started advertising with name: Volumio Oct 11 02:59:30 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "outputs"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "albumart"... Oct 11 02:59:30 volumio volumio[7114]: info: Plugin example_plugin is not enabled Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "inputs"... Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "updater_comm"... Oct 11 02:59:30 volumio volumio[7114]: info: Plugin mpdemulation is not enabled Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "rest_api"... Oct 11 02:59:30 volumio volumio[7114]: Forking 3 albumart workers Oct 11 02:59:30 volumio volumio[7114]: info: Loading plugin "websocket"... Oct 11 02:59:30 volumio volumio[7114]: info: Starting Socket.io Server version 2.3.0 Oct 11 02:59:30 volumio volumio[7114]: info: Loading i18n strings for locale zh Oct 11 02:59:30 volumio volumio[7114]: Updating browse sources language Oct 11 02:59:30 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::initPlayerControls Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: Express server listening on port 3000 Oct 11 02:59:31 volumio volumio[7114]: [Metrics] WebUI: 2s 376.44ms Oct 11 02:59:31 volumio volumio[7114]: info: Setting Device type: x86 Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::resetVolumioState Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::getcurrentVolume Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 02:59:31 volumio volumio[7114]: info: Completed loading Core Plugins Oct 11 02:59:31 volumio volumio[7114]: info: Preparing to generate the ALSA configuration file Oct 11 02:59:31 volumio volumio[7114]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 02:59:31 volumio volumio[7114]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1 Oct 11 02:59:31 volumio volumio[7114]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 11 02:59:31 volumio volumio[7114]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 11 02:59:31 volumio volumio[7114]: Starting albumart workers Oct 11 02:59:31 volumio volumio[7114]: Starting albumart workers Oct 11 02:59:31 volumio volumio[7114]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Oct 11 02:59:31 volumio volumio[7114]: Starting albumart workers Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:31 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:31 volumio volumio[7114]: info: Reloading queue from file Oct 11 02:59:31 volumio volumio[7114]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::pushState Oct 11 02:59:31 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:31 volumio volumio[7114]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:31 volumio volumio[7114]: info: Asound.conf file unchanged, so no further update is needed Oct 11 02:59:31 volumio volumio[7114]: info: Output device has changed, restarting MPD Oct 11 02:59:31 volumio volumio[7114]: info: Output device has changed, restarting Shairport Sync Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:31 volumio sudo[7190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:31 volumio sudo[7190]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:31 volumio sudo[7190]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:31 volumio sudo[7192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:31 volumio sudo[7192]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:31 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 02:59:31 volumio volumio[7114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:31 volumio volumio[7114]: info: ___________ START PLUGINS ___________ Oct 11 02:59:31 volumio volumio[7114]: info: ControllerMpd::onStart: Initializing MPD Oct 11 02:59:31 volumio volumio[7114]: info: Creating MPD Configuration file Oct 11 02:59:31 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:31 volumio volumio[7114]: info: [1728586771645] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:31 volumio sudo[7199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:31 volumio sudo[7199]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:31 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:31 volumio sudo[7199]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:31 volumio sudo[7201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:31 volumio sudo[7201]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:31 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 02:59:31 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:31 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:31 volumio volumio[7114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:31 volumio sudo[7208]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:31 volumio sudo[7208]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:31 volumio sudo[7208]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:31 volumio volumio[7114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:31 volumio volumio[7114]: info: [1728586771795] CoreMusicLibrary::Adding element Last_100 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:31 volumio volumio[7114]: info: [1728586771798] CoreMusicLibrary::Adding element Webradio Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:31 volumio volumio[7114]: info: Initializing BBC Radios Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:31 volumio volumio[7114]: info: Volumio Calling Home Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::setRepeat null single undefined Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::pushState Oct 11 02:59:31 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::setRandom null Oct 11 02:59:31 volumio volumio[7114]: info: CoreStateMachine::pushState Oct 11 02:59:31 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 02:59:31 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 02:59:32 volumio volumio[7114]: info: MPD Permissions set Oct 11 02:59:32 volumio volumio[7114]: info: MPD Permissions set Oct 11 02:59:32 volumio volumio[7114]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 11 02:59:32 volumio volumio[7114]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 11 02:59:32 volumio volumio[7114]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:32 volumio volumio[7114]: info: CoreStateMachine::pushState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:32 volumio volumio[7114]: info: Starting Shairport Sync Oct 11 02:59:32 volumio volumio[7114]: info: Starting Shairport Sync Oct 11 02:59:32 volumio sudo[7266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:32 volumio volumio[7114]: info: Starting Shairport Sync Oct 11 02:59:32 volumio sudo[7266]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:32 volumio sudo[7268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:32 volumio sudo[7268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:32 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:32 volumio volumio[7114]: info: Received Get System Info Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:32 volumio volumio[7114]: info: Discovery: Getting this device information Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio sudo[7272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:32 volumio sudo[7272]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:32 volumio volumio[7114]: info: Listing playlists Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio sudo[7268]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:32 volumio volumio[7114]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 02:59:32 volumio volumio[7114]: info: Discovery: Found device Volumio Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:32 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:32 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:32 volumio volumio[7114]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 02:59:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:32 volumio sudo[7272]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:32 volumio sudo[7266]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:32 volumio volumio[7114]: info: Shairport-Sync Started Oct 11 02:59:32 volumio volumio[7114]: Error adding Membership: Error: addMembership EINVAL Oct 11 02:59:32 volumio volumio[7114]: info: Shairport-Sync Started Oct 11 02:59:32 volumio volumio[7114]: info: Shairport-Sync Started Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:32 volumio volumio[7114]: info: Received Get System Info Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:32 volumio volumio[7114]: info: Discovery: Getting this device information Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:32 volumio volumio[7114]: info: Listing playlists Oct 11 02:59:32 volumio volumio[7114]: info: CoreCommandRouter::volumioGetQueue Oct 11 02:59:32 volumio volumio[7114]: info: CoreStateMachine::getQueue Oct 11 02:59:32 volumio volumio[7114]: info: CorePlayQueue::getQueue Oct 11 02:59:32 volumio mpd[7222]: Oct 11 02:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 02:59:32 volumio systemd[1]: Started Music Player Daemon. Oct 11 02:59:32 volumio sudo[7192]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:32 volumio sudo[7201]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:32 volumio volumio[7114]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Gloria%20Estefan/Destiny/d21739ce-aea0-4024-8ae5-30b77366e876.png' Oct 11 02:59:32 volumio volumio[7114]: info: Completed starting Core Plugins Oct 11 02:59:32 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:32 volumio volumio[7114]: info: ----- MyVolumio plugins startup ---- Oct 11 02:59:32 volumio volumio[7114]: info: ------------------------------------------- Oct 11 02:59:32 volumio volumio[7114]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 02:59:32 volumio volumio[7114]: error: MPD error: The expression evaluated to a falsy value: Oct 11 02:59:32 volumio volumio[7114]: assert.ok(self.idling) Oct 11 02:59:32 volumio volumio[7114]: error: The expression evaluated to a falsy value: Oct 11 02:59:32 volumio volumio[7114]: assert.ok(self.idling) Oct 11 02:59:32 volumio volumio[7114]: error: updateQueue error: null Oct 11 02:59:32 volumio volumio[7114]: info: MPD running with PID7222 Oct 11 02:59:32 volumio volumio[7114]: ,establishing connection Oct 11 02:59:32 volumio volumio[7114]: error: updateQueue error: null Oct 11 02:59:32 volumio volumio-remote-updater[733]: [2024-10-11 02:59:32] [connect] Successful connection Oct 11 02:59:32 volumio volumio-remote-updater[733]: [2024-10-11 02:59:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728586772 101 Oct 11 02:59:32 volumio volumio[7114]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Oct 11 02:59:32 volumio volumio[7114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:32 volumio volumio[7114]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 02:59:32 volumio volumio[7114]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:32 volumio volumio[7114]: errno: -111, Oct 11 02:59:32 volumio volumio[7114]: code: 'ECONNREFUSED', Oct 11 02:59:32 volumio volumio[7114]: syscall: 'connect', Oct 11 02:59:32 volumio volumio[7114]: address: '199.59.148.7', Oct 11 02:59:32 volumio volumio[7114]: port: 443 Oct 11 02:59:32 volumio volumio[7114]: } Oct 11 02:59:32 volumio volumio[7114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:33 volumio sudo[7291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:33 volumio sudo[7291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:33 volumio sudo[7291]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:33 volumio volumio-remote-updater[733]: [2024-10-11 02:59:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 11 02:59:33 volumio volumio-remote-updater[733]: [2024-10-11 02:59:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 11 02:59:33 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 02:59:33 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 02:59:33 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:33 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 02:59:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Oct 11 02:59:33 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 02:59:33 volumio systemd[1]: Started Volumio Backend Module. Oct 11 02:59:33 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:33 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:34 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:34 volumio volumio[7301]: info: ----- Volumio3 ---- Oct 11 02:59:34 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:34 volumio volumio[7301]: info: ----- System startup ---- Oct 11 02:59:34 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:34 volumio volumio[7301]: info: MYVOLUMIO Environment detected Oct 11 02:59:34 volumio volumio[7301]: info: Plugin folders cleanup Oct 11 02:59:34 volumio volumio[7301]: info: Scanning into folder /volumio/app/plugins/ Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category audio_interface Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category miscellanea Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category music_service Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category plugins.json Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category system_controller Oct 11 02:59:34 volumio volumio[7301]: info: Scanning category user_interface Oct 11 02:59:34 volumio volumio[7301]: info: Scanning into folder /data/plugins/ Oct 11 02:59:34 volumio volumio[7301]: info: Plugin folders cleanup completed Oct 11 02:59:34 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:34 volumio volumio[7301]: info: ----- Core plugins startup ---- Oct 11 02:59:34 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:34 volumio volumio[7301]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 02:59:34 volumio volumio[7301]: info: Adding plugin upnp to MyMusic Plugins Oct 11 02:59:34 volumio volumio[7301]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 02:59:34 volumio volumio[7301]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 02:59:34 volumio volumio[7301]: info: Loading plugins from folder /data/plugins/ Oct 11 02:59:34 volumio volumio[7301]: info: Loading plugin "system"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "appearance"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "network"... Oct 11 02:59:35 volumio volumio[7301]: info: Refreshing Cached IP Addresses Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "services"... Oct 11 02:59:35 volumio sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 02:59:35 volumio sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "alsa_controller"... Oct 11 02:59:35 volumio sudo[7328]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:35 volumio sudo[7330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 02:59:35 volumio sudo[7330]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:35 volumio sudo[7330]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:35 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "wizard"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "networkfs"... Oct 11 02:59:35 volumio volumio[7301]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 02:59:35 volumio volumio[7301]: info: Starting Udev Watcher for removable devices Oct 11 02:59:35 volumio volumio[7301]: info: Ignoring mount for partition: boot Oct 11 02:59:35 volumio volumio[7301]: info: Ignoring mount for partition: volumio Oct 11 02:59:35 volumio volumio[7301]: info: Ignoring mount for partition: volumio_data Oct 11 02:59:35 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "volumio_command_line_client"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "upnp"... Oct 11 02:59:35 volumio volumio[7301]: info: [1728586775713] Starting Upmpd Daemon Oct 11 02:59:35 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "my_music"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "mpd"... Oct 11 02:59:35 volumio volumio[7301]: info: Loading plugin "upnp_browser"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "alarm-clock"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "airplay_emulation"... Oct 11 02:59:36 volumio volumio[7301]: info: Starting Shairport Sync Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "last_100"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "webradio"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "i2s_dacs"... Oct 11 02:59:36 volumio volumio[7301]: info: I2S DAC not set, start Auto-detection Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "volumiodiscovery"... Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:36 volumio node[7301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** For more information see Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:36 volumio volumio[7301]: *** WARNING *** For more information see Oct 11 02:59:36 volumio node[7301]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:36 volumio node[7301]: *** WARNING *** For more information see Oct 11 02:59:36 volumio node[7301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:36 volumio node[7301]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:36 volumio node[7301]: *** WARNING *** For more information see Oct 11 02:59:36 volumio volumio[7301]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 02:59:36 volumio volumio[7301]: info: Discovery: Started advertising with name: Volumio Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "outputs"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "albumart"... Oct 11 02:59:36 volumio volumio[7301]: info: Plugin example_plugin is not enabled Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "inputs"... Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "updater_comm"... Oct 11 02:59:36 volumio volumio[7301]: info: Plugin mpdemulation is not enabled Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "rest_api"... Oct 11 02:59:36 volumio volumio[7301]: Forking 3 albumart workers Oct 11 02:59:36 volumio volumio[7301]: info: Loading plugin "websocket"... Oct 11 02:59:36 volumio volumio[7301]: info: Starting Socket.io Server version 2.3.0 Oct 11 02:59:36 volumio volumio[7301]: info: Loading i18n strings for locale zh Oct 11 02:59:36 volumio volumio[7301]: Updating browse sources language Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::initPlayerControls Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: Express server listening on port 3000 Oct 11 02:59:36 volumio volumio[7301]: [Metrics] WebUI: 2s 327.50ms Oct 11 02:59:36 volumio volumio[7301]: info: Setting Device type: x86 Oct 11 02:59:36 volumio volumio[7301]: info: CoreStateMachine::resetVolumioState Oct 11 02:59:36 volumio volumio[7301]: info: CoreStateMachine::getcurrentVolume Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 02:59:36 volumio volumio[7301]: info: Completed loading Core Plugins Oct 11 02:59:36 volumio volumio[7301]: info: Preparing to generate the ALSA configuration file Oct 11 02:59:36 volumio volumio[7301]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 02:59:36 volumio volumio[7301]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 11 02:59:36 volumio volumio[7301]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2 Oct 11 02:59:36 volumio volumio[7301]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Oct 11 02:59:36 volumio volumio[7301]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Oct 11 02:59:36 volumio volumio[7301]: info: Reloading queue from file Oct 11 02:59:36 volumio volumio[7301]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:36 volumio volumio[7301]: info: CoreStateMachine::pushState Oct 11 02:59:36 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:36 volumio volumio[7301]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:36 volumio volumio[7301]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:36 volumio volumio[7301]: Starting albumart workers Oct 11 02:59:36 volumio volumio[7301]: Starting albumart workers Oct 11 02:59:36 volumio volumio[7301]: info: Asound.conf file unchanged, so no further update is needed Oct 11 02:59:36 volumio volumio[7301]: info: Output device has changed, restarting MPD Oct 11 02:59:36 volumio volumio[7301]: Starting albumart workers Oct 11 02:59:36 volumio sudo[7376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:36 volumio sudo[7376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:36 volumio volumio[7301]: info: Output device has changed, restarting Shairport Sync Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:36 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:36 volumio sudo[7376]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:36 volumio sudo[7379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:36 volumio sudo[7379]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 02:59:37 volumio volumio[7301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:37 volumio volumio[7301]: info: ___________ START PLUGINS ___________ Oct 11 02:59:37 volumio volumio[7301]: info: ControllerMpd::onStart: Initializing MPD Oct 11 02:59:37 volumio volumio[7301]: info: Creating MPD Configuration file Oct 11 02:59:37 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:37 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:37 volumio volumio[7301]: info: [1728586777050] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:37 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:37 volumio sudo[7385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:37 volumio sudo[7385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio sudo[7385]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:37 volumio sudo[7388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:37 volumio sudo[7388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 02:59:37 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:37 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:37 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:37 volumio volumio[7301]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:37 volumio sudo[7391]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:37 volumio volumio[7301]: info: [1728586777224] CoreMusicLibrary::Adding element Last_100 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:37 volumio sudo[7391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:37 volumio volumio[7301]: info: [1728586777231] CoreMusicLibrary::Adding element Webradio Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:37 volumio sudo[7391]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:37 volumio volumio[7301]: info: Initializing BBC Radios Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: Volumio Calling Home Oct 11 02:59:37 volumio volumio[7301]: info: CoreStateMachine::setRepeat null single undefined Oct 11 02:59:37 volumio volumio[7301]: info: CoreStateMachine::pushState Oct 11 02:59:37 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:37 volumio volumio[7301]: info: CoreStateMachine::setRandom null Oct 11 02:59:37 volumio volumio[7301]: info: CoreStateMachine::pushState Oct 11 02:59:37 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:37 volumio volumio[7301]: info: MPD Permissions set Oct 11 02:59:37 volumio volumio[7301]: info: MPD Permissions set Oct 11 02:59:37 volumio volumio[7301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:37 volumio volumio[7301]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:37 volumio volumio[7301]: info: CoreStateMachine::pushState Oct 11 02:59:37 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:37 volumio volumio[7301]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 6 Oct 11 02:59:37 volumio volumio[7301]: info: Starting Shairport Sync Oct 11 02:59:37 volumio volumio[7301]: info: Starting Shairport Sync Oct 11 02:59:37 volumio volumio[7301]: info: Starting Shairport Sync Oct 11 02:59:37 volumio sudo[7452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:37 volumio sudo[7452]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio sudo[7454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:37 volumio sudo[7454]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:37 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:37 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:37 volumio sudo[7457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:37 volumio sudo[7457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:37 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 02:59:37 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 02:59:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:37 volumio sudo[7452]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:37 volumio volumio[7301]: info: Shairport-Sync Started Oct 11 02:59:37 volumio volumio[7301]: Error adding Membership: Error: addMembership EINVAL Oct 11 02:59:37 volumio sudo[7454]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:37 volumio volumio[7301]: info: Shairport-Sync Started Oct 11 02:59:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:37 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:37 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:37 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:37 volumio sudo[7457]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:37 volumio volumio[7301]: info: Shairport-Sync Started Oct 11 02:59:38 volumio mpd[7399]: Oct 11 02:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 02:59:38 volumio systemd[1]: Started Music Player Daemon. Oct 11 02:59:38 volumio sudo[7388]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:38 volumio sudo[7379]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:38 volumio volumio[7301]: info: Completed starting Core Plugins Oct 11 02:59:38 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:38 volumio volumio[7301]: info: ----- MyVolumio plugins startup ---- Oct 11 02:59:38 volumio volumio[7301]: info: ------------------------------------------- Oct 11 02:59:38 volumio volumio[7301]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 02:59:38 volumio volumio[7301]: error: MPD error: The expression evaluated to a falsy value: Oct 11 02:59:38 volumio volumio[7301]: assert.ok(self.idling) Oct 11 02:59:38 volumio volumio[7301]: error: The expression evaluated to a falsy value: Oct 11 02:59:38 volumio volumio[7301]: assert.ok(self.idling) Oct 11 02:59:38 volumio volumio[7301]: info: MPD running with PID7399 Oct 11 02:59:38 volumio volumio[7301]: ,establishing connection Oct 11 02:59:38 volumio volumio[7301]: error: MPD error: The expression evaluated to a falsy value: Oct 11 02:59:38 volumio volumio[7301]: assert.ok(self.idling) Oct 11 02:59:38 volumio volumio[7301]: error: The expression evaluated to a falsy value: Oct 11 02:59:38 volumio volumio[7301]: assert.ok(self.idling) Oct 11 02:59:38 volumio volumio[7301]: error: updateQueue error: null Oct 11 02:59:38 volumio volumio-remote-updater[733]: [2024-10-11 02:59:38] [connect] Successful connection Oct 11 02:59:38 volumio volumio-remote-updater[733]: [2024-10-11 02:59:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728586778 101 Oct 11 02:59:38 volumio volumio[7301]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 7 Oct 11 02:59:38 volumio volumio[7301]: info: Volumio called home Oct 11 02:59:38 volumio volumio[7301]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 02:59:38 volumio volumio[7301]: info: Discovery: Found device Volumio Oct 11 02:59:38 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:38 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:39 volumio volumio[7301]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:39 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:39 volumio volumio[7301]: info: Received Get System Info Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:39 volumio volumio[7301]: info: Discovery: Getting this device information Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:39 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:39 volumio volumio[7301]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:39 volumio volumio[7301]: info: Listing playlists Oct 11 02:59:39 volumio volumio[7301]: info: CoreCommandRouter::volumioGetQueue Oct 11 02:59:39 volumio volumio[7301]: info: CoreStateMachine::getQueue Oct 11 02:59:39 volumio volumio[7301]: info: CorePlayQueue::getQueue Oct 11 02:59:39 volumio volumio[7301]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Mariah%20Carey/Hero/6cef8451-c627-4aef-9756-08381c687af1.jpg' Oct 11 02:59:40 volumio volumio[7301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:40 volumio volumio[7301]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 02:59:40 volumio volumio[7301]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:40 volumio volumio[7301]: errno: -111, Oct 11 02:59:40 volumio volumio[7301]: code: 'ECONNREFUSED', Oct 11 02:59:40 volumio volumio[7301]: syscall: 'connect', Oct 11 02:59:40 volumio volumio[7301]: address: '199.59.148.7', Oct 11 02:59:40 volumio volumio[7301]: port: 443 Oct 11 02:59:40 volumio volumio[7301]: } Oct 11 02:59:40 volumio volumio[7301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:40 volumio sudo[7478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:40 volumio sudo[7478]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:40 volumio sudo[7478]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:40 volumio volumio-remote-updater[733]: [2024-10-11 02:59:40] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 11 02:59:40 volumio volumio-remote-updater[733]: [2024-10-11 02:59:40] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 11 02:59:40 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 02:59:40 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 02:59:40 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:40 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:40 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 02:59:40 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Oct 11 02:59:40 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:40 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 02:59:40 volumio systemd[1]: Started Volumio Backend Module. Oct 11 02:59:40 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:41 volumio volumio[7489]: info: ------------------------------------------- Oct 11 02:59:41 volumio volumio[7489]: info: ----- Volumio3 ---- Oct 11 02:59:41 volumio volumio[7489]: info: ------------------------------------------- Oct 11 02:59:41 volumio volumio[7489]: info: ----- System startup ---- Oct 11 02:59:41 volumio volumio[7489]: info: ------------------------------------------- Oct 11 02:59:42 volumio volumio[7489]: info: MYVOLUMIO Environment detected Oct 11 02:59:42 volumio volumio[7489]: info: Plugin folders cleanup Oct 11 02:59:42 volumio volumio[7489]: info: Scanning into folder /volumio/app/plugins/ Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category audio_interface Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category miscellanea Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category music_service Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category plugins.json Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category system_controller Oct 11 02:59:42 volumio volumio[7489]: info: Scanning category user_interface Oct 11 02:59:42 volumio volumio[7489]: info: Scanning into folder /data/plugins/ Oct 11 02:59:42 volumio volumio[7489]: info: Plugin folders cleanup completed Oct 11 02:59:42 volumio volumio[7489]: info: ------------------------------------------- Oct 11 02:59:42 volumio volumio[7489]: info: ----- Core plugins startup ---- Oct 11 02:59:42 volumio volumio[7489]: info: ------------------------------------------- Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 02:59:42 volumio volumio[7489]: info: Adding plugin upnp to MyMusic Plugins Oct 11 02:59:42 volumio volumio[7489]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 02:59:42 volumio volumio[7489]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugins from folder /data/plugins/ Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "system"... Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "appearance"... Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "network"... Oct 11 02:59:42 volumio volumio[7489]: info: Refreshing Cached IP Addresses Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "services"... Oct 11 02:59:42 volumio sudo[7515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "alsa_controller"... Oct 11 02:59:42 volumio sudo[7515]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:42 volumio sudo[7515]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:42 volumio sudo[7517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 02:59:42 volumio sudo[7517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:42 volumio sudo[7517]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:42 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "wizard"... Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "networkfs"... Oct 11 02:59:42 volumio volumio[7489]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 02:59:42 volumio volumio[7489]: info: Starting Udev Watcher for removable devices Oct 11 02:59:42 volumio volumio[7489]: info: Ignoring mount for partition: boot Oct 11 02:59:42 volumio volumio[7489]: info: Ignoring mount for partition: volumio Oct 11 02:59:42 volumio volumio[7489]: info: Ignoring mount for partition: volumio_data Oct 11 02:59:42 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "volumio_command_line_client"... Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "upnp"... Oct 11 02:59:42 volumio volumio[7489]: info: [1728586782969] Starting Upmpd Daemon Oct 11 02:59:42 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "my_music"... Oct 11 02:59:42 volumio volumio[7489]: info: Loading plugin "mpd"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "upnp_browser"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "alarm-clock"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "airplay_emulation"... Oct 11 02:59:43 volumio volumio[7489]: info: Starting Shairport Sync Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "last_100"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "webradio"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "i2s_dacs"... Oct 11 02:59:43 volumio volumio[7489]: info: I2S DAC not set, start Auto-detection Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "volumiodiscovery"... Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** For more information see Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:43 volumio node[7489]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:43 volumio volumio[7489]: *** WARNING *** For more information see Oct 11 02:59:43 volumio node[7489]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:43 volumio node[7489]: *** WARNING *** For more information see Oct 11 02:59:43 volumio node[7489]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:43 volumio node[7489]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:43 volumio node[7489]: *** WARNING *** For more information see Oct 11 02:59:43 volumio volumio[7489]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 02:59:43 volumio volumio[7489]: info: Discovery: Started advertising with name: Volumio Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "outputs"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "albumart"... Oct 11 02:59:43 volumio volumio[7489]: info: Plugin example_plugin is not enabled Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "inputs"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "updater_comm"... Oct 11 02:59:43 volumio volumio[7489]: info: Plugin mpdemulation is not enabled Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "rest_api"... Oct 11 02:59:43 volumio volumio[7489]: info: Loading plugin "websocket"... Oct 11 02:59:43 volumio volumio[7489]: Forking 3 albumart workers Oct 11 02:59:43 volumio volumio[7489]: info: Starting Socket.io Server version 2.3.0 Oct 11 02:59:43 volumio volumio[7489]: info: Loading i18n strings for locale zh Oct 11 02:59:43 volumio volumio[7489]: Updating browse sources language Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::initPlayerControls Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: Express server listening on port 3000 Oct 11 02:59:43 volumio volumio[7489]: [Metrics] WebUI: 2s 375.39ms Oct 11 02:59:43 volumio volumio[7489]: info: Setting Device type: x86 Oct 11 02:59:43 volumio volumio[7489]: info: CoreStateMachine::resetVolumioState Oct 11 02:59:43 volumio volumio[7489]: info: CoreStateMachine::getcurrentVolume Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:43 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:43 volumio volumio[7489]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 02:59:43 volumio volumio[7489]: info: Completed loading Core Plugins Oct 11 02:59:43 volumio volumio[7489]: info: Preparing to generate the ALSA configuration file Oct 11 02:59:43 volumio volumio[7489]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 02:59:44 volumio volumio[7489]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 11 02:59:44 volumio volumio[7489]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 2 Oct 11 02:59:44 volumio volumio[7489]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 11 02:59:44 volumio volumio[7489]: Starting albumart workers Oct 11 02:59:44 volumio volumio[7489]: Starting albumart workers Oct 11 02:59:44 volumio volumio[7489]: Starting albumart workers Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 02:59:44 volumio volumio[7489]: info: Reloading queue from file Oct 11 02:59:44 volumio volumio[7489]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Oct 11 02:59:44 volumio volumio[7489]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::pushState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:44 volumio volumio[7489]: info: Asound.conf file unchanged, so no further update is needed Oct 11 02:59:44 volumio volumio[7489]: info: Output device has changed, restarting MPD Oct 11 02:59:44 volumio sudo[7562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:44 volumio sudo[7562]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio volumio[7489]: info: Output device has changed, restarting Shairport Sync Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:44 volumio sudo[7562]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:44 volumio sudo[7564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:44 volumio sudo[7564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 02:59:44 volumio volumio[7489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:44 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:44 volumio volumio[7489]: info: ___________ START PLUGINS ___________ Oct 11 02:59:44 volumio volumio[7489]: info: ControllerMpd::onStart: Initializing MPD Oct 11 02:59:44 volumio volumio[7489]: info: Creating MPD Configuration file Oct 11 02:59:44 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:44 volumio volumio[7489]: info: [1728586784511] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:44 volumio sudo[7576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:44 volumio sudo[7576]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio sudo[7586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:44 volumio sudo[7586]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio sudo[7576]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:44 volumio sudo[7570]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:44 volumio sudo[7570]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio sudo[7570]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:44 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 02:59:44 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:44 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:44 volumio volumio[7489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:44 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:44 volumio volumio[7489]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:44 volumio volumio[7489]: info: [1728586784627] CoreMusicLibrary::Adding element Last_100 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:44 volumio volumio[7489]: info: [1728586784629] CoreMusicLibrary::Adding element Webradio Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:44 volumio volumio[7489]: info: Initializing BBC Radios Oct 11 02:59:44 volumio sudo[7604]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:44 volumio sudo[7604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:44 volumio sudo[7604]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: Volumio Calling Home Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::setRepeat null single undefined Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::pushState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::setRandom null Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::pushState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:44 volumio volumio[7489]: info: MPD Permissions set Oct 11 02:59:44 volumio volumio[7489]: info: MPD Permissions set Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:44 volumio volumio[7489]: info: Received Get System Info Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:44 volumio volumio[7489]: info: Discovery: Getting this device information Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: Listing playlists Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioGetQueue Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::getQueue Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getQueue Oct 11 02:59:44 volumio volumio[7489]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 02:59:44 volumio volumio[7489]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:44 volumio volumio[7489]: info: CoreStateMachine::pushState Oct 11 02:59:44 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:44 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:45 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:45 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:45 volumio volumio[7489]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:45 volumio volumio[7489]: info: Starting Shairport Sync Oct 11 02:59:45 volumio volumio[7489]: info: Starting Shairport Sync Oct 11 02:59:45 volumio sudo[7638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:45 volumio volumio[7489]: info: Starting Shairport Sync Oct 11 02:59:45 volumio sudo[7638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:45 volumio sudo[7640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:45 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:45 volumio sudo[7640]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:45 volumio sudo[7643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:45 volumio sudo[7643]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:45 volumio sudo[7638]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:45 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:45 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:45 volumio sudo[7643]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio sudo[7640]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:45 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:45 volumio volumio[7489]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 02:59:45 volumio volumio[7489]: info: Discovery: Found device Volumio Oct 11 02:59:45 volumio volumio[7489]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:45 volumio volumio[7489]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:45 volumio volumio[7489]: info: Shairport-Sync Started Oct 11 02:59:45 volumio volumio[7489]: Error adding Membership: Error: addMembership EINVAL Oct 11 02:59:45 volumio volumio[7489]: info: Shairport-Sync Started Oct 11 02:59:45 volumio volumio[7489]: info: Shairport-Sync Started Oct 11 02:59:45 volumio volumio[7489]: info: Volumio called home Oct 11 02:59:45 volumio volumio[7489]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:45 volumio volumio[7489]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 02:59:45 volumio volumio[7489]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:45 volumio volumio[7489]: errno: -111, Oct 11 02:59:45 volumio volumio[7489]: code: 'ECONNREFUSED', Oct 11 02:59:45 volumio volumio[7489]: syscall: 'connect', Oct 11 02:59:45 volumio volumio[7489]: address: '199.59.148.7', Oct 11 02:59:45 volumio volumio[7489]: port: 443 Oct 11 02:59:45 volumio volumio[7489]: } Oct 11 02:59:45 volumio volumio[7489]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:45 volumio mpd[7612]: Oct 11 02:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 02:59:45 volumio systemd[1]: Started Music Player Daemon. Oct 11 02:59:45 volumio sudo[7564]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio sudo[7586]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio sudo[7661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:45 volumio sudo[7661]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:45 volumio sudo[7661]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:45 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 02:59:45 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 02:59:45 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:45 volumio volumio-remote-updater[733]: [2024-10-11 02:59:45] [info] asio async_connect error: system:111 (Connection refused) Oct 11 02:59:45 volumio volumio-remote-updater[733]: [2024-10-11 02:59:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 11 02:59:45 volumio volumio-remote-updater[733]: [2024-10-11 02:59:45] [error] handle_connect error: Underlying Transport Error Oct 11 02:59:45 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:45 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 02:59:45 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Oct 11 02:59:45 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 02:59:45 volumio systemd[1]: Started Volumio Backend Module. Oct 11 02:59:45 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:45 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:46 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:46 volumio volumio[7671]: info: ----- Volumio3 ---- Oct 11 02:59:46 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:46 volumio volumio[7671]: info: ----- System startup ---- Oct 11 02:59:46 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:47 volumio volumio[7671]: info: MYVOLUMIO Environment detected Oct 11 02:59:47 volumio volumio[7671]: info: Plugin folders cleanup Oct 11 02:59:47 volumio volumio[7671]: info: Scanning into folder /volumio/app/plugins/ Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category audio_interface Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category miscellanea Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category music_service Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category plugins.json Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category system_controller Oct 11 02:59:47 volumio volumio[7671]: info: Scanning category user_interface Oct 11 02:59:47 volumio volumio[7671]: info: Scanning into folder /data/plugins/ Oct 11 02:59:47 volumio volumio[7671]: info: Plugin folders cleanup completed Oct 11 02:59:47 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:47 volumio volumio[7671]: info: ----- Core plugins startup ---- Oct 11 02:59:47 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 02:59:47 volumio volumio[7671]: info: Adding plugin upnp to MyMusic Plugins Oct 11 02:59:47 volumio volumio[7671]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 02:59:47 volumio volumio[7671]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugins from folder /data/plugins/ Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "system"... Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "appearance"... Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "network"... Oct 11 02:59:47 volumio volumio[7671]: info: Refreshing Cached IP Addresses Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "services"... Oct 11 02:59:47 volumio sudo[7698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 02:59:47 volumio sudo[7698]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "alsa_controller"... Oct 11 02:59:47 volumio sudo[7698]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:47 volumio sudo[7700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 02:59:47 volumio sudo[7700]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:47 volumio sudo[7700]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:47 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "wizard"... Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "networkfs"... Oct 11 02:59:47 volumio volumio[7671]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 02:59:47 volumio volumio[7671]: info: Starting Udev Watcher for removable devices Oct 11 02:59:47 volumio volumio[7671]: info: Ignoring mount for partition: boot Oct 11 02:59:47 volumio volumio[7671]: info: Ignoring mount for partition: volumio Oct 11 02:59:47 volumio volumio[7671]: info: Ignoring mount for partition: volumio_data Oct 11 02:59:47 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "volumio_command_line_client"... Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "upnp"... Oct 11 02:59:47 volumio volumio[7671]: info: [1728586787974] Starting Upmpd Daemon Oct 11 02:59:47 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "my_music"... Oct 11 02:59:47 volumio volumio[7671]: info: Loading plugin "mpd"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "upnp_browser"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "alarm-clock"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "airplay_emulation"... Oct 11 02:59:48 volumio volumio[7671]: info: Starting Shairport Sync Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "last_100"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "webradio"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "i2s_dacs"... Oct 11 02:59:48 volumio volumio[7671]: info: I2S DAC not set, start Auto-detection Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "volumiodiscovery"... Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:48 volumio node[7671]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** For more information see Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:48 volumio volumio[7671]: *** WARNING *** For more information see Oct 11 02:59:48 volumio node[7671]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:48 volumio node[7671]: *** WARNING *** For more information see Oct 11 02:59:48 volumio node[7671]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:48 volumio node[7671]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:48 volumio node[7671]: *** WARNING *** For more information see Oct 11 02:59:48 volumio volumio[7671]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 02:59:48 volumio volumio[7671]: info: Discovery: Started advertising with name: Volumio Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "outputs"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "albumart"... Oct 11 02:59:48 volumio volumio[7671]: info: Plugin example_plugin is not enabled Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "inputs"... Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "updater_comm"... Oct 11 02:59:48 volumio volumio[7671]: info: Plugin mpdemulation is not enabled Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "rest_api"... Oct 11 02:59:48 volumio volumio[7671]: Forking 3 albumart workers Oct 11 02:59:48 volumio volumio[7671]: info: Loading plugin "websocket"... Oct 11 02:59:48 volumio volumio[7671]: info: Starting Socket.io Server version 2.3.0 Oct 11 02:59:48 volumio volumio[7671]: info: Loading i18n strings for locale zh Oct 11 02:59:48 volumio volumio[7671]: Updating browse sources language Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::initPlayerControls Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: Express server listening on port 3000 Oct 11 02:59:48 volumio volumio[7671]: [Metrics] WebUI: 2s 372.87ms Oct 11 02:59:48 volumio volumio[7671]: info: Setting Device type: x86 Oct 11 02:59:48 volumio volumio[7671]: info: CoreStateMachine::resetVolumioState Oct 11 02:59:48 volumio volumio[7671]: info: CoreStateMachine::getcurrentVolume Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:48 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:48 volumio volumio[7671]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 02:59:48 volumio volumio[7671]: info: Completed loading Core Plugins Oct 11 02:59:48 volumio volumio[7671]: info: Preparing to generate the ALSA configuration file Oct 11 02:59:48 volumio volumio[7671]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 02:59:49 volumio volumio[7671]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1 Oct 11 02:59:49 volumio volumio[7671]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2 Oct 11 02:59:49 volumio volumio[7671]: Starting albumart workers Oct 11 02:59:49 volumio volumio[7671]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 3 Oct 11 02:59:49 volumio volumio[7671]: Starting albumart workers Oct 11 02:59:49 volumio volumio[7671]: Starting albumart workers Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: Reloading queue from file Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 02:59:49 volumio volumio[7671]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::pushState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:49 volumio volumio[7671]: info: Asound.conf file unchanged, so no further update is needed Oct 11 02:59:49 volumio volumio[7671]: info: Output device has changed, restarting MPD Oct 11 02:59:49 volumio sudo[7745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:49 volumio sudo[7745]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio sudo[7745]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:49 volumio volumio[7671]: info: Output device has changed, restarting Shairport Sync Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio sudo[7748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:49 volumio sudo[7748]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 02:59:49 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:49 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:49 volumio volumio[7671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:49 volumio volumio[7671]: info: ___________ START PLUGINS ___________ Oct 11 02:59:49 volumio volumio[7671]: info: ControllerMpd::onStart: Initializing MPD Oct 11 02:59:49 volumio volumio[7671]: info: Creating MPD Configuration file Oct 11 02:59:49 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:49 volumio sudo[7755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:49 volumio volumio[7671]: info: [1728586789468] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:49 volumio sudo[7755]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio sudo[7755]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:49 volumio sudo[7760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:49 volumio sudo[7760]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio sudo[7753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:49 volumio sudo[7753]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio sudo[7753]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:49 volumio volumio[7671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:49 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:49 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:49 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:49 volumio volumio[7671]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:49 volumio volumio[7671]: info: [1728586789598] CoreMusicLibrary::Adding element Last_100 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:49 volumio volumio[7671]: info: [1728586789601] CoreMusicLibrary::Adding element Webradio Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:49 volumio volumio[7671]: info: Initializing BBC Radios Oct 11 02:59:49 volumio sudo[7786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:49 volumio sudo[7786]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:49 volumio sudo[7786]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: Volumio Calling Home Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::setRepeat null single undefined Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::pushState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::setRandom null Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::pushState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:49 volumio volumio[7671]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4 Oct 11 02:59:49 volumio volumio[7671]: info: MPD Permissions set Oct 11 02:59:49 volumio volumio[7671]: info: MPD Permissions set Oct 11 02:59:49 volumio volumio[7671]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:49 volumio volumio[7671]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:49 volumio volumio[7671]: info: CoreStateMachine::pushState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:49 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:49 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:49 volumio volumio[7671]: info: Starting Shairport Sync Oct 11 02:59:49 volumio volumio[7671]: info: Starting Shairport Sync Oct 11 02:59:50 volumio sudo[7823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:50 volumio volumio[7671]: info: Starting Shairport Sync Oct 11 02:59:50 volumio sudo[7823]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:50 volumio sudo[7825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:50 volumio sudo[7825]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:50 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:50 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:50 volumio volumio[7671]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 02:59:50 volumio volumio[7671]: info: Discovery: Found device Volumio Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:50 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:50 volumio sudo[7828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:50 volumio sudo[7828]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:50 volumio sudo[7823]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio volumio[7671]: info: Received Get System Info Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:50 volumio volumio[7671]: info: Discovery: Getting this device information Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:50 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:50 volumio volumio[7671]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:50 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:50 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:50 volumio volumio[7671]: info: Listing playlists Oct 11 02:59:50 volumio volumio[7671]: info: CoreCommandRouter::volumioGetQueue Oct 11 02:59:50 volumio volumio[7671]: info: CoreStateMachine::getQueue Oct 11 02:59:50 volumio volumio[7671]: info: CorePlayQueue::getQueue Oct 11 02:59:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:50 volumio sudo[7828]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio sudo[7825]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio volumio[7671]: info: Shairport-Sync Started Oct 11 02:59:50 volumio volumio[7671]: Error adding Membership: Error: addMembership EINVAL Oct 11 02:59:50 volumio volumio[7671]: info: Shairport-Sync Started Oct 11 02:59:50 volumio volumio[7671]: info: Shairport-Sync Started Oct 11 02:59:50 volumio volumio[7671]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Celine%20Dion/The%20Collector%27s%20Series%20Volume%20One/1dafbc35-3631-41a7-ab11-ddd72e3da984.jpg' Oct 11 02:59:50 volumio mpd[7800]: Oct 11 02:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 02:59:50 volumio systemd[1]: Started Music Player Daemon. Oct 11 02:59:50 volumio sudo[7748]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio sudo[7760]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio volumio[7671]: info: Completed starting Core Plugins Oct 11 02:59:50 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:50 volumio volumio[7671]: info: ----- MyVolumio plugins startup ---- Oct 11 02:59:50 volumio volumio[7671]: info: ------------------------------------------- Oct 11 02:59:50 volumio volumio[7671]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 02:59:50 volumio volumio[7671]: error: MPD error: The expression evaluated to a falsy value: Oct 11 02:59:50 volumio volumio[7671]: assert.ok(self.idling) Oct 11 02:59:50 volumio volumio[7671]: error: The expression evaluated to a falsy value: Oct 11 02:59:50 volumio volumio[7671]: assert.ok(self.idling) Oct 11 02:59:50 volumio volumio[7671]: info: MPD running with PID7800 Oct 11 02:59:50 volumio volumio[7671]: ,establishing connection Oct 11 02:59:50 volumio volumio[7671]: error: updateQueue error: null Oct 11 02:59:50 volumio volumio[7671]: error: updateQueue error: null Oct 11 02:59:50 volumio volumio[7671]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:50 volumio volumio[7671]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 02:59:50 volumio volumio[7671]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:50 volumio volumio[7671]: errno: -111, Oct 11 02:59:50 volumio volumio[7671]: code: 'ECONNREFUSED', Oct 11 02:59:50 volumio volumio[7671]: syscall: 'connect', Oct 11 02:59:50 volumio volumio[7671]: address: '199.59.148.7', Oct 11 02:59:50 volumio volumio[7671]: port: 443 Oct 11 02:59:50 volumio volumio[7671]: } Oct 11 02:59:50 volumio volumio[7671]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:50 volumio sudo[7848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:50 volumio sudo[7848]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:50 volumio sudo[7848]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:50 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 02:59:50 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 02:59:50 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:50 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:51 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 02:59:51 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Oct 11 02:59:51 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 02:59:51 volumio systemd[1]: Started Volumio Backend Module. Oct 11 02:59:51 volumio systemd[1]: Started dynamicswap service. Oct 11 02:59:51 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 02:59:52 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:52 volumio volumio[7858]: info: ----- Volumio3 ---- Oct 11 02:59:52 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:52 volumio volumio[7858]: info: ----- System startup ---- Oct 11 02:59:52 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:52 volumio volumio[7858]: info: MYVOLUMIO Environment detected Oct 11 02:59:52 volumio volumio[7858]: info: Plugin folders cleanup Oct 11 02:59:52 volumio volumio[7858]: info: Scanning into folder /volumio/app/plugins/ Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category audio_interface Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category miscellanea Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category music_service Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category plugins.json Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category system_controller Oct 11 02:59:52 volumio volumio[7858]: info: Scanning category user_interface Oct 11 02:59:52 volumio volumio[7858]: info: Scanning into folder /data/plugins/ Oct 11 02:59:52 volumio volumio[7858]: info: Plugin folders cleanup completed Oct 11 02:59:52 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:52 volumio volumio[7858]: info: ----- Core plugins startup ---- Oct 11 02:59:52 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:52 volumio volumio[7858]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 02:59:52 volumio volumio[7858]: info: Adding plugin upnp to MyMusic Plugins Oct 11 02:59:52 volumio volumio[7858]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 02:59:52 volumio volumio[7858]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 02:59:52 volumio volumio[7858]: info: Loading plugins from folder /data/plugins/ Oct 11 02:59:52 volumio volumio[7858]: info: Loading plugin "system"... Oct 11 02:59:52 volumio volumio[7858]: info: Loading plugin "appearance"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "network"... Oct 11 02:59:53 volumio volumio[7858]: info: Refreshing Cached IP Addresses Oct 11 02:59:53 volumio sudo[7885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 02:59:53 volumio sudo[7885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "services"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "alsa_controller"... Oct 11 02:59:53 volumio sudo[7885]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:53 volumio sudo[7887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 02:59:53 volumio sudo[7887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:53 volumio sudo[7887]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:53 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "wizard"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "networkfs"... Oct 11 02:59:53 volumio volumio[7858]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 02:59:53 volumio volumio[7858]: info: Starting Udev Watcher for removable devices Oct 11 02:59:53 volumio volumio-remote-updater[733]: [2024-10-11 02:59:53] [connect] Successful connection Oct 11 02:59:53 volumio volumio[7858]: info: Ignoring mount for partition: boot Oct 11 02:59:53 volumio volumio[7858]: info: Ignoring mount for partition: volumio Oct 11 02:59:53 volumio volumio[7858]: info: Ignoring mount for partition: volumio_data Oct 11 02:59:53 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "volumio_command_line_client"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "upnp"... Oct 11 02:59:53 volumio volumio[7858]: info: [1728586793224] Starting Upmpd Daemon Oct 11 02:59:53 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "my_music"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "mpd"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "upnp_browser"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "alarm-clock"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "airplay_emulation"... Oct 11 02:59:53 volumio volumio[7858]: info: Starting Shairport Sync Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "last_100"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "webradio"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "i2s_dacs"... Oct 11 02:59:53 volumio volumio[7858]: info: I2S DAC not set, start Auto-detection Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "volumiodiscovery"... Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** For more information see Oct 11 02:59:53 volumio node[7858]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:53 volumio volumio[7858]: *** WARNING *** For more information see Oct 11 02:59:53 volumio node[7858]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:53 volumio node[7858]: *** WARNING *** For more information see Oct 11 02:59:53 volumio node[7858]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 02:59:53 volumio node[7858]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 02:59:53 volumio node[7858]: *** WARNING *** For more information see Oct 11 02:59:53 volumio volumio[7858]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 02:59:53 volumio volumio[7858]: info: Discovery: Started advertising with name: Volumio Oct 11 02:59:53 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "outputs"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "albumart"... Oct 11 02:59:53 volumio volumio[7858]: info: Plugin example_plugin is not enabled Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "inputs"... Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "updater_comm"... Oct 11 02:59:53 volumio volumio[7858]: info: Plugin mpdemulation is not enabled Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "rest_api"... Oct 11 02:59:53 volumio volumio[7858]: Forking 3 albumart workers Oct 11 02:59:53 volumio volumio[7858]: info: Loading plugin "websocket"... Oct 11 02:59:53 volumio volumio[7858]: info: Starting Socket.io Server version 2.3.0 Oct 11 02:59:53 volumio volumio[7858]: info: Loading i18n strings for locale zh Oct 11 02:59:53 volumio volumio[7858]: Updating browse sources language Oct 11 02:59:53 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::initPlayerControls Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: Express server listening on port 3000 Oct 11 02:59:54 volumio volumio[7858]: [Metrics] WebUI: 2s 358.64ms Oct 11 02:59:54 volumio volumio[7858]: info: Setting Device type: x86 Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::resetVolumioState Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::getcurrentVolume Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 02:59:54 volumio volumio[7858]: info: Completed loading Core Plugins Oct 11 02:59:54 volumio volumio[7858]: info: Preparing to generate the ALSA configuration file Oct 11 02:59:54 volumio volumio[7858]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 02:59:54 volumio volumio[7858]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 11 02:59:54 volumio volumio[7858]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2 Oct 11 02:59:54 volumio volumio[7858]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3 Oct 11 02:59:54 volumio volumio-remote-updater[733]: [2024-10-11 02:59:54] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728586793 101 Oct 11 02:59:54 volumio volumio[7858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Oct 11 02:59:54 volumio volumio[7858]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Oct 11 02:59:54 volumio volumio[7858]: Starting albumart workers Oct 11 02:59:54 volumio volumio[7858]: Starting albumart workers Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:54 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:54 volumio volumio[7858]: Starting albumart workers Oct 11 02:59:54 volumio volumio[7858]: info: Reloading queue from file Oct 11 02:59:54 volumio volumio[7858]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::pushState Oct 11 02:59:54 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::updateTrackBlock Oct 11 02:59:54 volumio volumio[7858]: info: CorePlayQueue::getTrackBlock Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 02:59:54 volumio volumio[7858]: info: Asound.conf file unchanged, so no further update is needed Oct 11 02:59:54 volumio volumio[7858]: info: Output device has changed, restarting MPD Oct 11 02:59:54 volumio sudo[7932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:54 volumio sudo[7932]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio volumio[7858]: info: Output device has changed, restarting Shairport Sync Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:54 volumio sudo[7932]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:54 volumio sudo[7934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:54 volumio sudo[7934]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 02:59:54 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:54 volumio volumio[7858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:54 volumio volumio[7858]: info: ___________ START PLUGINS ___________ Oct 11 02:59:54 volumio volumio[7858]: info: ControllerMpd::onStart: Initializing MPD Oct 11 02:59:54 volumio volumio[7858]: info: Creating MPD Configuration file Oct 11 02:59:54 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:54 volumio sudo[7942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 02:59:54 volumio sudo[7942]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio volumio[7858]: info: [1728586794645] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:54 volumio sudo[7942]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:54 volumio sudo[7944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 02:59:54 volumio sudo[7944]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio sudo[7940]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:54 volumio sudo[7940]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio sudo[7940]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:54 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 02:59:54 volumio systemd[1]: mpd.service: Succeeded. Oct 11 02:59:54 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 02:59:54 volumio systemd[1]: Starting Music Player Daemon... Oct 11 02:59:54 volumio volumio[7858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:54 volumio sudo[7947]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 02:59:54 volumio sudo[7947]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:54 volumio sudo[7947]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:54 volumio volumio[7858]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:54 volumio volumio[7858]: info: [1728586794826] CoreMusicLibrary::Adding element Last_100 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 02:59:54 volumio volumio[7858]: info: [1728586794829] CoreMusicLibrary::Adding element Webradio Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:54 volumio volumio[7858]: info: Initializing BBC Radios Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:54 volumio volumio[7858]: info: Volumio Calling Home Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::setRepeat null single undefined Oct 11 02:59:54 volumio volumio[7858]: info: CoreStateMachine::pushState Oct 11 02:59:54 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:54 volumio volumio[7858]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:55 volumio volumio[7858]: info: CoreStateMachine::setRandom null Oct 11 02:59:55 volumio volumio[7858]: info: CoreStateMachine::pushState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 02:59:55 volumio volumio[7858]: info: MPD Permissions set Oct 11 02:59:55 volumio volumio[7858]: info: MPD Permissions set Oct 11 02:59:55 volumio volumio[7858]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 02:59:55 volumio volumio[7858]: info: VolumeController:: Volume=10 Mute =false Oct 11 02:59:55 volumio volumio[7858]: info: CoreStateMachine::pushState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioPushState Oct 11 02:59:55 volumio volumio[7858]: info: Starting Shairport Sync Oct 11 02:59:55 volumio volumio[7858]: info: Starting Shairport Sync Oct 11 02:59:55 volumio sudo[8008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:55 volumio volumio[7858]: info: Starting Shairport Sync Oct 11 02:59:55 volumio sudo[8008]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:55 volumio sudo[8010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:55 volumio sudo[8010]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:55 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:55 volumio sudo[8014]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 02:59:55 volumio sudo[8014]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:55 volumio sudo[8008]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:55 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 02:59:55 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 02:59:55 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 02:59:55 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:55 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 02:59:55 volumio volumio[7858]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 02:59:55 volumio volumio[7858]: info: Discovery: Found device Volumio Oct 11 02:59:55 volumio sudo[8010]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio sudo[8014]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:55 volumio volumio[7858]: info: Shairport-Sync Started Oct 11 02:59:55 volumio volumio[7858]: Error adding Membership: Error: addMembership EINVAL Oct 11 02:59:55 volumio volumio[7858]: info: Shairport-Sync Started Oct 11 02:59:55 volumio volumio[7858]: info: Shairport-Sync Started Oct 11 02:59:55 volumio mpd[7979]: Oct 11 02:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 02:59:55 volumio systemd[1]: Started Music Player Daemon. Oct 11 02:59:55 volumio sudo[7944]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:55 volumio sudo[7934]: pam_unix(sudo:session): session closed for user root Oct 11 02:59:55 volumio volumio[7858]: info: Completed starting Core Plugins Oct 11 02:59:55 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:55 volumio volumio[7858]: info: ----- MyVolumio plugins startup ---- Oct 11 02:59:55 volumio volumio[7858]: info: ------------------------------------------- Oct 11 02:59:55 volumio volumio[7858]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 02:59:55 volumio volumio[7858]: error: MPD error: The expression evaluated to a falsy value: Oct 11 02:59:55 volumio volumio[7858]: assert.ok(self.idling) Oct 11 02:59:55 volumio volumio[7858]: error: The expression evaluated to a falsy value: Oct 11 02:59:55 volumio volumio[7858]: assert.ok(self.idling) Oct 11 02:59:55 volumio volumio[7858]: info: MPD running with PID7979 Oct 11 02:59:55 volumio volumio[7858]: ,establishing connection Oct 11 02:59:55 volumio volumio[7858]: error: updateQueue error: null Oct 11 02:59:55 volumio volumio[7858]: error: updateQueue error: null Oct 11 02:59:55 volumio volumio[7858]: info: Volumio called home Oct 11 02:59:55 volumio volumio[7858]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:55 volumio volumio[7858]: info: Received Get System Info Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:55 volumio volumio[7858]: info: Discovery: Getting this device information Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:55 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:55 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:55 volumio volumio[7858]: info: Listing playlists Oct 11 02:59:58 volumio volumio[7858]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8 Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 02:59:58 volumio volumio[7858]: info: Cannot mount NAS czhsh at system boot, trial number 2 ,retrying in 5 seconds Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:58 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 02:59:58 volumio volumio[7858]: info: Received Get System Info Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 02:59:58 volumio volumio[7858]: info: Discovery: Getting this device information Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:58 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::volumioGetState Oct 11 02:59:58 volumio volumio[7858]: info: CorePlayQueue::getTrack 0 Oct 11 02:59:58 volumio volumio[7858]: info: Listing playlists Oct 11 02:59:58 volumio volumio[7858]: info: CoreCommandRouter::volumioGetQueue Oct 11 02:59:58 volumio volumio[7858]: info: CoreStateMachine::getQueue Oct 11 02:59:58 volumio volumio[7858]: info: CorePlayQueue::getQueue Oct 11 02:59:59 volumio volumio[7858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:59 volumio volumio[7858]: Error: connect ECONNREFUSED 199.59.148.7:80 Oct 11 02:59:59 volumio volumio[7858]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 02:59:59 volumio volumio[7858]: errno: -111, Oct 11 02:59:59 volumio volumio[7858]: code: 'ECONNREFUSED', Oct 11 02:59:59 volumio volumio[7858]: syscall: 'connect', Oct 11 02:59:59 volumio volumio[7858]: address: '199.59.148.7', Oct 11 02:59:59 volumio volumio[7858]: port: 80 Oct 11 02:59:59 volumio volumio[7858]: } Oct 11 02:59:59 volumio volumio[7858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 02:59:59 volumio sudo[8037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:58 Oct 11 02:59:59 volumio sudo[8037]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 02:59:59 volumio sudo[8037]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:01 volumio volumio-remote-updater[733]: [2024-10-11 03:00:01] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 11 03:00:01 volumio volumio-remote-updater[733]: [2024-10-11 03:00:01] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 11 03:00:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 03:00:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 03:00:01 volumio systemd[1]: Started dynamicswap service. Oct 11 03:00:01 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 03:00:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 03:00:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Oct 11 03:00:01 volumio systemd[1]: Started dynamicswap service. Oct 11 03:00:01 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 03:00:01 volumio systemd[1]: Started Volumio Backend Module. Oct 11 03:00:01 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 03:00:02 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:02 volumio volumio[8053]: info: ----- Volumio3 ---- Oct 11 03:00:02 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:02 volumio volumio[8053]: info: ----- System startup ---- Oct 11 03:00:02 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:02 volumio volumio[8053]: info: MYVOLUMIO Environment detected Oct 11 03:00:02 volumio volumio[8053]: info: Plugin folders cleanup Oct 11 03:00:02 volumio volumio[8053]: info: Scanning into folder /volumio/app/plugins/ Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category audio_interface Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category miscellanea Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category music_service Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category plugins.json Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category system_controller Oct 11 03:00:02 volumio volumio[8053]: info: Scanning category user_interface Oct 11 03:00:02 volumio volumio[8053]: info: Scanning into folder /data/plugins/ Oct 11 03:00:02 volumio volumio[8053]: info: Plugin folders cleanup completed Oct 11 03:00:02 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:02 volumio volumio[8053]: info: ----- Core plugins startup ---- Oct 11 03:00:02 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:02 volumio volumio[8053]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 03:00:02 volumio volumio[8053]: info: Adding plugin upnp to MyMusic Plugins Oct 11 03:00:02 volumio volumio[8053]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 03:00:02 volumio volumio[8053]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 03:00:02 volumio volumio[8053]: info: Loading plugins from folder /data/plugins/ Oct 11 03:00:02 volumio volumio[8053]: info: Loading plugin "system"... Oct 11 03:00:02 volumio volumio[8053]: info: Loading plugin "appearance"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "network"... Oct 11 03:00:03 volumio volumio[8053]: info: Refreshing Cached IP Addresses Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "services"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "alsa_controller"... Oct 11 03:00:03 volumio sudo[8079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 03:00:03 volumio sudo[8079]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:03 volumio sudo[8079]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:03 volumio sudo[8081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 03:00:03 volumio sudo[8081]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:03 volumio sudo[8081]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:03 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "wizard"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "networkfs"... Oct 11 03:00:03 volumio volumio[8053]: info: Cannot mount NAS czhsh at system boot, trial number 1 ,retrying in 5 seconds Oct 11 03:00:03 volumio volumio[8053]: info: Starting Udev Watcher for removable devices Oct 11 03:00:03 volumio volumio[8053]: info: Ignoring mount for partition: boot Oct 11 03:00:03 volumio volumio[8053]: info: Ignoring mount for partition: volumio Oct 11 03:00:03 volumio volumio[8053]: info: Ignoring mount for partition: volumio_data Oct 11 03:00:03 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "volumio_command_line_client"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "upnp"... Oct 11 03:00:03 volumio volumio[8053]: info: [1728586803464] Starting Upmpd Daemon Oct 11 03:00:03 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "my_music"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "mpd"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "upnp_browser"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "alarm-clock"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "airplay_emulation"... Oct 11 03:00:03 volumio volumio[8053]: info: Starting Shairport Sync Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "last_100"... Oct 11 03:00:03 volumio volumio[8053]: info: Loading plugin "webradio"... Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "i2s_dacs"... Oct 11 03:00:04 volumio volumio[8053]: info: I2S DAC not set, start Auto-detection Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "volumiodiscovery"... Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 03:00:04 volumio node[8053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** For more information see Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 03:00:04 volumio node[8053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 03:00:04 volumio volumio[8053]: *** WARNING *** For more information see Oct 11 03:00:04 volumio node[8053]: *** WARNING *** For more information see Oct 11 03:00:04 volumio node[8053]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 03:00:04 volumio node[8053]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 03:00:04 volumio node[8053]: *** WARNING *** For more information see Oct 11 03:00:04 volumio volumio[8053]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 03:00:04 volumio volumio[8053]: info: Discovery: Started advertising with name: Volumio Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "outputs"... Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "albumart"... Oct 11 03:00:04 volumio volumio[8053]: info: Plugin example_plugin is not enabled Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "inputs"... Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "updater_comm"... Oct 11 03:00:04 volumio volumio[8053]: info: Plugin mpdemulation is not enabled Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "rest_api"... Oct 11 03:00:04 volumio volumio[8053]: info: Loading plugin "websocket"... Oct 11 03:00:04 volumio volumio[8053]: Forking 3 albumart workers Oct 11 03:00:04 volumio volumio[8053]: info: Starting Socket.io Server version 2.3.0 Oct 11 03:00:04 volumio volumio[8053]: info: Loading i18n strings for locale zh Oct 11 03:00:04 volumio volumio[8053]: Updating browse sources language Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::initPlayerControls Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: Express server listening on port 3000 Oct 11 03:00:04 volumio volumio[8053]: [Metrics] WebUI: 2s 355.57ms Oct 11 03:00:04 volumio volumio[8053]: info: Setting Device type: x86 Oct 11 03:00:04 volumio volumio[8053]: info: CoreStateMachine::resetVolumioState Oct 11 03:00:04 volumio volumio[8053]: info: CoreStateMachine::getcurrentVolume Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 11 03:00:04 volumio volumio[8053]: info: Completed loading Core Plugins Oct 11 03:00:04 volumio volumio[8053]: info: Preparing to generate the ALSA configuration file Oct 11 03:00:04 volumio volumio[8053]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 11 03:00:04 volumio volumio[8053]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Oct 11 03:00:04 volumio volumio[8053]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Oct 11 03:00:04 volumio volumio[8053]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3 Oct 11 03:00:04 volumio volumio[8053]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Oct 11 03:00:04 volumio volumio[8053]: Starting albumart workers Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:04 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:04 volumio volumio[8053]: Starting albumart workers Oct 11 03:00:04 volumio volumio[8053]: Starting albumart workers Oct 11 03:00:04 volumio volumio[8053]: info: Reloading queue from file Oct 11 03:00:04 volumio volumio[8053]: info: Asound.conf file unchanged, so no further update is needed Oct 11 03:00:04 volumio volumio[8053]: info: Output device has changed, restarting MPD Oct 11 03:00:04 volumio volumio[8053]: info: Output device has changed, restarting Shairport Sync Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:04 volumio sudo[8125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 03:00:04 volumio sudo[8125]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:04 volumio sudo[8127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 03:00:04 volumio sudo[8127]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:04 volumio sudo[8125]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:04 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 03:00:04 volumio systemd[1]: mpd.service: Succeeded. Oct 11 03:00:04 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 03:00:04 volumio volumio[8053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 03:00:04 volumio volumio[8053]: info: ___________ START PLUGINS ___________ Oct 11 03:00:04 volumio volumio[8053]: info: ControllerMpd::onStart: Initializing MPD Oct 11 03:00:04 volumio volumio[8053]: info: Creating MPD Configuration file Oct 11 03:00:04 volumio systemd[1]: Starting Music Player Daemon... Oct 11 03:00:04 volumio sudo[8135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 03:00:04 volumio sudo[8135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:04 volumio sudo[8135]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 03:00:04 volumio volumio[8053]: info: [1728586804812] CoreMusicLibrary::Adding element 媒体服务器 Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 03:00:04 volumio sudo[8138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 03:00:04 volumio sudo[8138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:04 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 03:00:04 volumio systemd[1]: mpd.service: Succeeded. Oct 11 03:00:04 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:04 volumio systemd[1]: Starting Music Player Daemon... Oct 11 03:00:04 volumio volumio[8053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:04 volumio sudo[8140]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 03:00:04 volumio sudo[8140]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:04 volumio sudo[8140]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:04 volumio volumio[8053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 03:00:04 volumio volumio[8053]: info: [1728586804983] CoreMusicLibrary::Adding element Last_100 Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 03:00:04 volumio volumio[8053]: info: [1728586804987] CoreMusicLibrary::Adding element Webradio Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 03:00:04 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 03:00:04 volumio volumio[8053]: info: Initializing BBC Radios Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: Volumio Calling Home Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::setRepeat null single undefined Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::pushState Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioPushState Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::setRandom null Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::pushState Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioPushState Oct 11 03:00:05 volumio volumio[8053]: info: VolumeController:: Volume=10 Mute =false Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::pushState Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioPushState Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::updateTrackBlock Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrackBlock Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 11 03:00:05 volumio volumio[8053]: info: MPD Permissions set Oct 11 03:00:05 volumio volumio[8053]: info: MPD Permissions set Oct 11 03:00:05 volumio volumio[8053]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 03:00:05 volumio volumio[8053]: info: VolumeController:: Volume=10 Mute =false Oct 11 03:00:05 volumio volumio[8053]: info: CoreStateMachine::pushState Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioPushState Oct 11 03:00:05 volumio volumio[8053]: info: Starting Shairport Sync Oct 11 03:00:05 volumio volumio[8053]: info: Starting Shairport Sync Oct 11 03:00:05 volumio sudo[8202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 03:00:05 volumio sudo[8202]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:05 volumio volumio[8053]: info: Starting Shairport Sync Oct 11 03:00:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 03:00:05 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 03:00:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 03:00:05 volumio sudo[8204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 03:00:05 volumio sudo[8204]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:05 volumio sudo[8207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 11 03:00:05 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:05 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:05 volumio sudo[8207]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 03:00:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 03:00:05 volumio sudo[8202]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 11 03:00:05 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 11 03:00:05 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 11 03:00:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 11 03:00:05 volumio volumio[8053]: info: Shairport-Sync Started Oct 11 03:00:05 volumio volumio[8053]: Error adding Membership: Error: addMembership EINVAL Oct 11 03:00:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 11 03:00:05 volumio sudo[8204]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:05 volumio volumio[8053]: info: Shairport-Sync Started Oct 11 03:00:05 volumio sudo[8207]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:05 volumio volumio[8053]: info: Shairport-Sync Started Oct 11 03:00:05 volumio volumio[8053]: info: Volumio called home Oct 11 03:00:05 volumio mpd[8148]: Oct 11 03:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 03:00:05 volumio systemd[1]: Started Music Player Daemon. Oct 11 03:00:05 volumio sudo[8138]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:05 volumio sudo[8127]: pam_unix(sudo:session): session closed for user root Oct 11 03:00:05 volumio volumio[8053]: info: Completed starting Core Plugins Oct 11 03:00:05 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:05 volumio volumio[8053]: info: ----- MyVolumio plugins startup ---- Oct 11 03:00:05 volumio volumio[8053]: info: ------------------------------------------- Oct 11 03:00:05 volumio volumio[8053]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 11 03:00:05 volumio volumio[8053]: error: MPD error: The expression evaluated to a falsy value: Oct 11 03:00:05 volumio volumio[8053]: assert.ok(self.idling) Oct 11 03:00:05 volumio volumio[8053]: error: The expression evaluated to a falsy value: Oct 11 03:00:05 volumio volumio[8053]: assert.ok(self.idling) Oct 11 03:00:05 volumio volumio[8053]: error: updateQueue error: null Oct 11 03:00:05 volumio volumio[8053]: info: MPD running with PID8148 Oct 11 03:00:05 volumio volumio[8053]: ,establishing connection Oct 11 03:00:05 volumio volumio[8053]: error: updateQueue error: null Oct 11 03:00:06 volumio volumio-remote-updater[733]: [2024-10-11 03:00:06] [connect] Successful connection Oct 11 03:00:06 volumio volumio-remote-updater[733]: [2024-10-11 03:00:06] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728586806 101 Oct 11 03:00:06 volumio volumio[8053]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Oct 11 03:00:06 volumio volumio[8053]: info: Discovery: adding 96a6ba88-0462-4bcf-873c-3b74cdcfc462 Oct 11 03:00:06 volumio volumio[8053]: info: Discovery: Found device Volumio Oct 11 03:00:06 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:06 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:07 volumio volumio[8053]: verbose: New Socket.io Connection to 192.168.1.17 from 192.168.1.206 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7 Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:07 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 03:00:07 volumio volumio[8053]: info: Received Get System Info Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 03:00:07 volumio volumio[8053]: info: Discovery: Getting this device information Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:07 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::volumioGetState Oct 11 03:00:07 volumio volumio[8053]: info: CorePlayQueue::getTrack 0 Oct 11 03:00:07 volumio volumio[8053]: info: Listing playlists Oct 11 03:00:07 volumio volumio[8053]: info: CoreCommandRouter::volumioGetQueue Oct 11 03:00:07 volumio volumio[8053]: info: CoreStateMachine::getQueue Oct 11 03:00:07 volumio volumio[8053]: info: CorePlayQueue::getQueue Oct 11 03:00:07 volumio volumio[8053]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Celine%20Dion/efc4a1f7-2175-464c-8ddd-b4930c52684f.jpg' Oct 11 03:00:07 volumio volumio[8053]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Michael%20Jackson/1996%20Grammy%20Nominees/617c01e6-5ab1-481c-93aa-849c0668f521.jpg' Oct 11 03:00:08 volumio volumio[8053]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 03:00:08 volumio volumio[8053]: Error: connect ECONNREFUSED 199.59.148.7:443 Oct 11 03:00:08 volumio volumio[8053]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 11 03:00:08 volumio volumio[8053]: errno: -111, Oct 11 03:00:08 volumio volumio[8053]: code: 'ECONNREFUSED', Oct 11 03:00:08 volumio volumio[8053]: syscall: 'connect', Oct 11 03:00:08 volumio volumio[8053]: address: '199.59.148.7', Oct 11 03:00:08 volumio volumio[8053]: port: 443 Oct 11 03:00:08 volumio volumio[8053]: } Oct 11 03:00:08 volumio volumio[8053]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 03:00:08 volumio sudo[8228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 02:59 Oct 11 03:00:08 volumio sudo[8228]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"