Aug 31 11:46:08 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 31 11:46:08 volumio-16 volumio[1383]: info: browseTIDALUri took 282 milliseconds Aug 31 11:46:08 volumio-16 volumio[1383]: info: Preload queue cleared Aug 31 11:46:08 volumio-16 volumio[1383]: info: Preload queue cleared Aug 31 11:46:10 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Aug 31 11:46:10 volumio-16 volumio[1383]: info: browseTIDALUri took 208 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preload queue cleared Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/306947906 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/107228997 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/74291818 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/211435442 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/42941401 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/92531070 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/238082519 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/213389246 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/69688301 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/121065663 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/74214069 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/69891148 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/180109280 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/203559388 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/231593802 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/124233109 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/99696432 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/199580288 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/122194199 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/226735766 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/74055739 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/95267708 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/80699847 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/208081880 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/276562375 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/211435416 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/238082525 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/37992697 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/36267212 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/235758012 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/146944877 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/120661997 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/176487742 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/74419992 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/413510149 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/413510153 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/10565647 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/187469651 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/66764288 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/185302542 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/99648630 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/200337594 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/300316798 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/302242346 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/270344890 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/16819391 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/46741462 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/14275646 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/19637653 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/70676944 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/210796655 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/23551904 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/23146101 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/66854547 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/19451165 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/17232368 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/329696850 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/321163819 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/97762710 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/238082528 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/459101284 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/359798 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/240698996 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/110740513 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/46564700 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/103214580 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/239205265 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/238082521 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/491222302 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/6871485 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/340437533 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/15799993 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/246567985 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/32368734 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/64731511 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/386275890 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/19826801 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/154240315 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/281464423 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/403432046 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Preloading song: tidal://song/221292140 Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/306947906 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 47 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/107228997 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/74291818 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/211435442 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/42941401 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 104 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 55 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/92531070 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/238082519 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 65 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 139 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/213389246 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 56 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/69688301 in service tidal Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:10 volumio-16 volumio[1383]: info: Exploding uri tidal://song/121065663 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/74214069 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/69891148 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 106 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/180109280 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 52 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/203559388 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/231593802 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/124233109 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/99696432 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/199580288 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/122194199 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/226735766 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 58 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/74055739 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/95267708 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 61 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/80699847 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/208081880 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/276562375 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 52 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/211435416 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 57 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/238082525 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/37992697 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/36267212 in service tidal Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 63 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:11 volumio-16 volumio[1383]: info: Exploding uri tidal://song/235758012 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/146944877 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/120661997 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 55 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/176487742 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 52 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/74419992 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 57 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/413510149 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 52 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/413510153 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 55 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/10565647 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/187469651 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 60 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/66764288 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/185302542 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 58 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/99648630 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/200337594 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 83 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/300316798 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/302242346 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/270344890 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/16819391 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/46741462 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/14275646 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 48 milliseconds Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/19637653 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: Exploding uri tidal://song/70676944 in service tidal Aug 31 11:46:12 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/210796655 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/23551904 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/23146101 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/66854547 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/19451165 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/17232368 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 59 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/329696850 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/321163819 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/97762710 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/238082528 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 52 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/459101284 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/359798 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/240698996 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 53 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 47 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/110740513 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/46564700 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/103214580 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 48 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/239205265 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/238082521 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 48 milliseconds Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/491222302 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: Exploding uri tidal://song/6871485 in service tidal Aug 31 11:46:13 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/340437533 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 72 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/15799993 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 77 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/246567985 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 56 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/32368734 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 61 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/64731511 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 51 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/386275890 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 48 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/19826801 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/154240315 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 49 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/281464423 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/403432046 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 54 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 48 milliseconds Aug 31 11:46:14 volumio-16 volumio[1383]: info: Exploding uri tidal://song/221292140 in service tidal Aug 31 11:46:14 volumio-16 volumio[1383]: info: explodeTIDALUri took 50 milliseconds Aug 31 11:46:16 volumio-16 volumio[1383]: info: Preload queue cleared Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreStateMachine::ClearQueue Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreStateMachine::stop Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 11:46:16 volumio-16 volumio[1383]: info: CorePlayQueue::clearPlayQueue Aug 31 11:46:16 volumio-16 volumio[1383]: info: CorePlayQueue::saveQueue Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Aug 31 11:46:16 volumio-16 volumio[1383]: info: CoreStateMachine::addQueueItems Aug 31 11:46:16 volumio-16 volumio[1383]: info: CorePlayQueue::addQueueItems Aug 31 11:46:16 volumio-16 volumio[1383]: info: Preload queue cleared Aug 31 11:46:16 volumio-16 volumio[1383]: info: Adding Item to queue: tidal://playlist/ec2fd25d-4fc3-4c6b-a6cc-de9fdf99cc51 Aug 31 11:46:16 volumio-16 volumio[1383]: info: Exploding uri tidal://playlist/ec2fd25d-4fc3-4c6b-a6cc-de9fdf99cc51 in service tidal Aug 31 11:46:17 volumio-16 volumio[1383]: info: explodeTIDALUri took 209 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::saveQueue Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::updateTrackBlock Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrackBlock Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPlay Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::play index 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::stop Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::play index undefined Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::startPlaybackTimer Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: [1788191177064] ControllerTidal::clearAddPlayTrack Aug 31 11:46:17 volumio-16 volumio[1383]: info: Getting stream with soundQuality HI_RES Aug 31 11:46:17 volumio-16 volumio[1383]: info: getStreamUrl took 71 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand stop took 0 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces system playlist update Aug 31 11:46:17 volumio-16 volumio[1383]: info: Ignoring MPD Status Update Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand clear took 1 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==" Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces system playlist update Aug 31 11:46:17 volumio-16 volumio[1383]: info: Ignoring MPD Status Update Aug 31 11:46:17 volumio-16 volumio[1383]: error: updateQueue error: null Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces system playlist update Aug 31 11:46:17 volumio-16 volumio[1383]: info: Ignoring MPD Status Update Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 2ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==" took 2 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 1ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces system playlist update Aug 31 11:46:17 volumio-16 volumio[1383]: info: Ignoring MPD Status Update Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 1ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand play took 1 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 0ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand status took 8 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:17 volumio-16 volumio[1383]: info: Aug 31 11:46:17 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand status took 10 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"782 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus stop Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 13ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand status took 3 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"782 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus play Aug 31 11:46:17 volumio-16 volumio[1383]: info: Received an update from plugin. extracting info from payload Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"595 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus play Aug 31 11:46:17 volumio-16 volumio[1383]: info: Received an update from plugin. extracting info from payload Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.298Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.298Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.299Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.299Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.300Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.300Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.302Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.302Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 32ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 23ms Aug 31 11:46:17 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"721 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:17 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus play Aug 31 11:46:17 volumio-16 volumio[1383]: info: Received an update from plugin. extracting info from payload Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:17 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:17 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.321Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.321Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.321Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:17.321Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:17 volumio-16 volumio[1383]: info: ------------------------------ 43ms Aug 31 11:46:18 volumio-16 kernel: usb 3-1.1: USB disconnect, device number 3 Aug 31 11:46:18 volumio-16 thd[1038]: Error reading device '/dev/input/event5' Aug 31 11:46:18 volumio-16 thd[1038]: Error reading device '/dev/input/event5' Aug 31 11:46:18 volumio-16 thd[1038]: Error reading device '/dev/input/event6' Aug 31 11:46:18 volumio-16 thd[1038]: Error reading device '/dev/input/event6' Aug 31 11:46:19 volumio-16 volumio[1383]: info: Executing endpoint metavolumio Aug 31 11:46:19 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 11:46:19 volumio-16 volumio[1383]: info: Executing endpoint metavolumio Aug 31 11:46:19 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 11:46:19 volumio-16 volumio[1383]: info: Executing endpoint metavolumio Aug 31 11:46:19 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::servicePause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePause Aug 31 11:46:25 volumio-16 volumio[1383]: info: [1788191185690] ControllerTidal::pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 11:46:25 volumio-16 volumio[1383]: info: ControllerMpd::pause Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: Aug 31 11:46:25 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:25 volumio-16 volumio[1383]: info: sendMpdCommand pause took 1 milliseconds Aug 31 11:46:25 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:25 volumio-16 volumio[1383]: info: Aug 31 11:46:25 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:25 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:25 volumio-16 volumio[1383]: info: sendMpdCommand status took 0 milliseconds Aug 31 11:46:25 volumio-16 volumio[1383]: info: sendMpdCommand status took 1 milliseconds Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:25 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:46:25 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:25 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9834,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:25 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:46:25 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":9834,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:25 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:25 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:25 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:25 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:46:25 volumio-16 volumio[1383]: info: ------------------------------ 13ms Aug 31 11:46:25 volumio-16 volumio[1383]: info: ------------------------------ 12ms Aug 31 11:46:25 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:25.707Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=8539 volume=100 Aug 31 11:46:25 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:25.707Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:25 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:25.707Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=8539 volume=100 Aug 31 11:46:25 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:25.707Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:25 volumio-16 volumio[1383]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: new full-speed USB device number 4 using xhci-hcd Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: Product: TouchScreen Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: Manufacturer: wch.cn Aug 31 11:46:27 volumio-16 kernel: usb 3-1.1: SerialNumber: 9LQ0172005164 Aug 31 11:46:28 volumio-16 kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.1/3-1.1:1.0/0003:27C0:0859.0004/input/input8 Aug 31 11:46:28 volumio-16 kernel: hid-multitouch 0003:27C0:0859.0004: input,hiddev96,hidraw0: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input0 Aug 31 11:46:28 volumio-16 kernel: hid-generic 0003:27C0:0859.0005: hiddev97,hidraw1: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input1 Aug 31 11:46:28 volumio-16 kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.1/3-1.1:1.2/0003:27C0:0859.0006/input/input9 Aug 31 11:46:28 volumio-16 kernel: hid-generic 0003:27C0:0859.0006: input,hidraw2: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input2 Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.387Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=9.213141ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.405Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.412Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=7.011576ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.428Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=22.489204ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.430Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=24.46546ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.437Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=31.002422ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.446Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://securetoken.googleapis.com duration=40.610593ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.458Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://functions.volumio.cloud duration=52.443801ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.463Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://functions.volumio.cloud duration=56.669947ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.479Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://google.com duration=73.199454ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.511Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://www.googleapis.com duration=105.73963ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.518Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=http://pushupdates.volumio.org duration=111.192378ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.558Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=https://database.volumio.cloud duration=151.243004ms Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.635Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=http://plugins.volumio.org duration=228.102154ms Aug 31 11:46:31 volumio-16 sudo[7808]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 11:46:31 volumio-16 sudo[7808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:46:31 volumio-16 sudo[7808]: pam_unix(sudo:session): session closed for user root Aug 31 11:46:31 volumio-16 sudo[7811]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 11:46:31 volumio-16 sudo[7811]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:46:31 volumio-16 sudo[7811]: pam_unix(sudo:session): session closed for user root Aug 31 11:46:31 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:31.695Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.777594ms timeout=10s endpoint=http://cddb.volumio.org duration=288.608042ms Aug 31 11:46:31 volumio-16 volumio[1383]: verbose: New Socket.io Connection to 192.168.50.161 from 192.168.50.29 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 13 Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 11:46:31 volumio-16 volumio[1383]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 11:46:31 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:46:31 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: Listing playlists Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 11:46:31 volumio-16 sudo[7814]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 11:46:31 volumio-16 sudo[7814]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:46:31 volumio-16 sudo[7814]: pam_unix(sudo:session): session closed for user root Aug 31 11:46:31 volumio-16 sudo[7816]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 11:46:31 volumio-16 sudo[7816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:46:31 volumio-16 sudo[7816]: pam_unix(sudo:session): session closed for user root Aug 31 11:46:31 volumio-16 volumio[1383]: verbose: New Socket.io Connection to 192.168.50.161 from 192.168.50.29 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 11:46:31 volumio-16 volumio[1383]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 11:46:31 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:46:31 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:31 volumio-16 volumio[1383]: info: Listing playlists Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 11:46:31 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 31 11:46:33 volumio-16 kernel: usb 3-1.1: USB disconnect, device number 4 Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 11:46:33 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:46:33 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:33 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:46:34 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 11:46:34 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:46:34 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:46:34 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:46:34 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:46:34 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:34 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:46:35 volumio-16 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:46:35 volumio-16 volumio[1383]: info: touch_display: About to detect HDMI ports... Aug 31 11:46:35 volumio-16 volumio[1383]: info: touch_display: Detected HDMI ports: HDMI 0 (HDMI-1), HDMI 1 (HDMI-2) Aug 31 11:46:40 volumio-16 volumio[1383]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Aug 31 11:46:40 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Aug 31 11:46:40 volumio-16 volumio[1383]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 11:46:41 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 11:46:43 volumio-16 kernel: usb 3-1.1: new full-speed USB device number 5 using xhci-hcd Aug 31 11:46:44 volumio-16 kernel: usb 3-1.1: New USB device found, idVendor=27c0, idProduct=0859, bcdDevice= 1.50 Aug 31 11:46:44 volumio-16 kernel: usb 3-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 11:46:44 volumio-16 kernel: usb 3-1.1: Product: TouchScreen Aug 31 11:46:44 volumio-16 kernel: usb 3-1.1: Manufacturer: wch.cn Aug 31 11:46:44 volumio-16 kernel: usb 3-1.1: SerialNumber: 9LQ0172005164 Aug 31 11:46:44 volumio-16 kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.1/3-1.1:1.0/0003:27C0:0859.0007/input/input10 Aug 31 11:46:44 volumio-16 kernel: hid-multitouch 0003:27C0:0859.0007: input,hiddev96,hidraw0: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input0 Aug 31 11:46:44 volumio-16 kernel: hid-generic 0003:27C0:0859.0008: hiddev97,hidraw1: USB HID v1.10 Device [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input1 Aug 31 11:46:44 volumio-16 kernel: input: wch.cn TouchScreen as /devices/platform/axi/1000120000.pcie/1f00300000.usb/xhci-hcd.1/usb3/3-1/3-1.1/3-1.1:1.2/0003:27C0:0859.0009/input/input11 Aug 31 11:46:44 volumio-16 kernel: hid-generic 0003:27C0:0859.0009: input,hidraw2: USB HID v1.10 Mouse [wch.cn TouchScreen] on usb-xhci-hcd.1-1.1/input2 Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioSeek Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::seek Aug 31 11:46:45 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:45 volumio-16 volumio[1383]: info: TRACKBLOCK {"album":"Winter","albumUri":"tidal://album/306947904","albumart":"https://resources.tidal.com/images/e23f59ad/3d1e/47d9/90c7/c82c85be2b0f/640x640.jpg","artist":"Vancouver Sleep Clinic","artistUri":"tidal://artist/5044415","audioQuality":"LOSSLESS","bitdepth":"16 bit","duration":199,"explicit":false,"name":"Flaws","samplerate":"44.1 KHz","service":"tidal","title":"Flaws","trackType":"tidal","tracknumber":2,"type":"track","uri":"tidal://song/306947906","volumeNumber":1} Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::startPlaybackTimer Aug 31 11:46:45 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:45 volumio-16 volumio[1383]: info: [1788191205899] ControllerTidal::seek Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 11:46:45 volumio-16 volumio[1383]: info: ControllerMpd::seek Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.904Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=81000 volume=100 Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.906Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:45 volumio-16 volumio[1383]: error: null Aug 31 11:46:45 volumio-16 volumio[1383]: info: Aug 31 11:46:45 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:45 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:45 volumio-16 volumio[1383]: info: Aug 31 11:46:45 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:45 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:45 volumio-16 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Aug 31 11:46:45 volumio-16 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:45 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:46:45 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:45 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":81000,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:46:45 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":81000,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"657 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:45 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:45 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:45 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:46:45 volumio-16 volumio[1383]: info: ------------------------------ 15ms Aug 31 11:46:45 volumio-16 volumio[1383]: info: ------------------------------ 14ms Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.926Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=81000 volume=100 Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.926Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.926Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=81000 volume=100 Aug 31 11:46:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:45.926Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPlay Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::play index undefined Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 31 11:46:46 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::startPlaybackTimer Aug 31 11:46:46 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:46 volumio-16 volumio[1383]: info: [1788191206580] ControllerTidal::resume Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 11:46:46 volumio-16 volumio[1383]: info: ControllerMpd::resume Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Aug 31 11:46:46 volumio-16 volumio[1383]: info: Aug 31 11:46:46 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:46 volumio-16 volumio[1383]: info: sendMpdCommand play took 2 milliseconds Aug 31 11:46:46 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:46 volumio-16 volumio[1383]: info: Aug 31 11:46:46 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:46:46 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:46:46 volumio-16 volumio[1383]: info: sendMpdCommand status took 1 milliseconds Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:46 volumio-16 volumio[1383]: info: sendMpdCommand status took 1 milliseconds Aug 31 11:46:46 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:46 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82100,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"811 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.589Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=81251 volume=100 Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.589Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:46 volumio-16 volumio[1383]: info: ------------------------------ 8ms Aug 31 11:46:46 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:46:46 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"play","position":0,"seek":82100,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"811 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:46:46 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService play Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus play Aug 31 11:46:46 volumio-16 volumio[1383]: info: Received an update from plugin. extracting info from payload Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:46:46 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:46:46 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.598Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=81251 volume=100 Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.598Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:46 volumio-16 volumio[1383]: info: ------------------------------ 16ms Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.598Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PLAYING positionMs=81251 volume=100 Aug 31 11:46:46 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:46:46.599Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 11:46:50 volumio-16 volumio[1383]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 31 11:46:50 volumio-16 volumio[1383]: info: Received Get System Version Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 11:46:50 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:46:50 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:46:50 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::servicePause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePause Aug 31 11:47:21 volumio-16 volumio[1383]: info: [1788191241383] ControllerTidal::pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 31 11:47:21 volumio-16 volumio[1383]: info: ControllerMpd::pause Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: Aug 31 11:47:21 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:47:21 volumio-16 volumio[1383]: info: sendMpdCommand pause took 1 milliseconds Aug 31 11:47:21 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:47:21 volumio-16 volumio[1383]: info: Aug 31 11:47:21 volumio-16 volumio[1383]: ---------------------------- MPD announces state update: player Aug 31 11:47:21 volumio-16 volumio[1383]: info: ControllerMpd::getState Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Aug 31 11:47:21 volumio-16 volumio[1383]: info: sendMpdCommand status took 1 milliseconds Aug 31 11:47:21 volumio-16 volumio[1383]: info: sendMpdCommand status took 1 milliseconds Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::parseState Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 31 11:47:21 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:47:21 volumio-16 volumio[1383]: info: sendMpdCommand playlistinfo took 0 milliseconds Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Aug 31 11:47:21 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117221,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"705 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:21 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:47:21 volumio-16 volumio[1383]: info: ControllerMpd::pushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":117221,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"705 Kbps","isStreaming":false,"title":"0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MjZkOWYyNDk1MDhjMmQzYjUzM2UzZDM5YzkzMGNiY182MS5tcDQ/0.flac?token=1788194777~NDllNzJhYzkyMmU5ZWU4OWUxYmM3NWRmYTk0M2FiNDMzOGZlNzc2Mg==","trackType":"tidal"} Aug 31 11:47:21 volumio-16 volumio[1383]: verbose: CURRENT POSITION 0 Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::syncState stateService pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::syncState currentStatus pause Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:21 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:21 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:21 volumio-16 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Aug 31 11:47:21 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:21.394Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116045 volume=100 Aug 31 11:47:21 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:21.394Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:21 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:21.395Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116045 volume=100 Aug 31 11:47:21 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:21.395Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:21 volumio-16 volumio[1383]: info: ------------------------------ 11ms Aug 31 11:47:21 volumio-16 volumio[1383]: info: ------------------------------ 11ms Aug 31 11:47:21 volumio-16 volumio[1383]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 11:47:27 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:47:37 volumio-16 volumio[1383]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 31 11:47:37 volumio-16 volumio[1383]: info: Preparing to save Alsa Options, stopping services first Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPause Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreStateMachine::pause Aug 31 11:47:37 volumio-16 volumio[1383]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: Enabling I2S DAC: Adafruit MAX98357 Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 sudo[7988]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 31 11:47:37 volumio-16 sudo[7988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[7988]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: No Overlays Loaded Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 sudo[7991]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay max98357a Aug 31 11:47:37 volumio-16 sudo[7991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Aug 31 11:47:37 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a0000/status Aug 31 11:47:37 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Aug 31 11:47:37 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,format Aug 31 11:47:37 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/simple-audio-card,name Aug 31 11:47:37 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Aug 31 11:47:37 volumio-16 sudo[7991]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 (udev-worker)[8005]: controlC2: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 2' failed with exit code 99. Aug 31 11:47:37 volumio-16 volumio[1383]: info: Device 2 does not have any Mixer Control Available Aug 31 11:47:37 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 vtcs[2976]: [2026-08-31 11:47:37.503] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 31 11:47:37 volumio-16 vtcs[2976]: [2026-08-31 11:47:37.503] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 31 11:47:37 volumio-16 sudo[8014]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8014]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 11:47:37 volumio-16 volumio[1383]: info: Updating Volume Controller Parameters: Device: 2 Name: Adafruit MAX98357 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 11:47:37 volumio-16 volumio[1383]: info: Disabling external Volume Control Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:37 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:37 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 31 11:47:37 volumio-16 systemd[1]: vtcs.service: Killing process 2983 (vtcs) with signal SIGKILL. Aug 31 11:47:37 volumio-16 systemd[1]: vtcs.service: Deactivated successfully. Aug 31 11:47:37 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:37.529Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116296 volume=100 Aug 31 11:47:37 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:37 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:37.530Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:37 volumio-16 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 31 11:47:37 volumio-16 sudo[8014]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:37 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:37 volumio-16 sudo[8029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: I2S Param [object Object] successfully enabled Aug 31 11:47:37 volumio-16 sudo[8021]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8029]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:37 volumio-16 volumio[1383]: info: Getting Alsa Cards List without I2S DAC Aug 31 11:47:37 volumio-16 sudo[8032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 31 11:47:37 volumio-16 qobuz-connect[2951]: 20260831 11:47:37.624 [2951.2951] INFO SampleApp: Stopping Local configuration server Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:47:37 volumio-16 volumio[1383]: info: Asound.conf file written Aug 31 11:47:37 volumio-16 sudo[8050]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 11:47:37 volumio-16 sudo[8050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8050]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card MAX98357A Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "simple-card" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card MAX98357A Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:37 volumio-16 sudo[8056]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:37 volumio-16 sudo[8056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8056]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 sudo[8060]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:37 volumio-16 sudo[8060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 11:47:37 volumio-16 sudo[8069]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:37 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 sudo[8076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:37 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:37 volumio-16 sudo[8076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:37 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:37 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:37 volumio-16 sudo[8076]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8069]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8078]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:37 volumio-16 sudo[8078]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:37 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 sudo[8090]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8090]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:37 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:37 volumio-16 sudo[8101]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8101]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8090]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:37 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:37 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:37 volumio-16 sudo[8110]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8110]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:37 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 sudo[8115]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8115]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:37 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:37 volumio-16 sudo[8110]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:37 volumio-16 sudo[8133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8101]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:37 volumio-16 sudo[8098]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8115]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8125]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:37 volumio-16 sudo[8125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:37 volumio-16 sudo[8138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:37 volumio-16 sudo[8138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:37 volumio-16 sudo[8125]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:37 volumio-16 sudo[8134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:37 volumio-16 sudo[8140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:37 volumio-16 sudo[8145]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:37 volumio-16 sudo[8145]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:37 volumio-16 sudo[8149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:37 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:37 volumio-16 volumio[1383]: info: Asound.conf file written Aug 31 11:47:37 volumio-16 sudo[8154]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 11:47:37 volumio-16 sudo[8154]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8154]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card MAX98357A Aug 31 11:47:37 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 31 11:47:37 volumio-16 volumio[1383]: Found hardware: "simple-card" "" "" "" "" Aug 31 11:47:37 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:37 volumio-16 volumio[1383]: No state is present for card MAX98357A Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:37 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:37 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:37 volumio-16 systemd[1]: shairport-sync.service: Consumed 1.679s CPU time. Aug 31 11:47:37 volumio-16 sudo[8160]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:37 volumio-16 sudo[8160]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8162]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:37 volumio-16 sudo[8162]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 sudo[8160]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:37 volumio-16 sudo[8138]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 sudo[8149]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:37 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:37 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:37 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:37 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:37 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:37 volumio-16 sudo[8174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:37 volumio-16 sudo[8174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:37 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:37 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:38 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:38 volumio-16 sudo[8145]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:38 volumio-16 sudo[8134]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:38 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:38 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:38 volumio-16 sudo[8200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:38 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:38 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:38 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:38 volumio-16 sudo[8200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:38 volumio-16 volumio[1383]: (node:1383) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 31 11:47:38 volumio-16 volumio[1383]: (Use `node --trace-warnings ...` to show where the warning was created) Aug 31 11:47:38 volumio-16 volumio[1383]: (node:1383) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 31 11:47:38 volumio-16 volumio[1383]: (node:1383) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 31 11:47:38 volumio-16 volumio[1383]: (node:1383) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 31 11:47:38 volumio-16 volumio[1383]: (node:1383) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 sudo[8200]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 sudo[8174]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:38 volumio-16 sudo[8205]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:38 volumio-16 sudo[8205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:38 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:38 volumio-16 sudo[8211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8205]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8181]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:38 volumio-16 sudo[8217]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:38 volumio-16 sudo[8217]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 volumio[1383]: info: Asound.conf file unchanged, so no further update is needed Aug 31 11:47:38 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:38 volumio-16 sudo[8221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:38 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:38 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 sudo[8227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:38 volumio-16 sudo[8227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8223]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:38 volumio-16 sudo[8223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:38 volumio-16 sudo[8223]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:38 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:38 volumio-16 sudo[8237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:38 volumio-16 sudo[8237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8217]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:38 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:38 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:38 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 sudo[8257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:38 volumio-16 sudo[8257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8237]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 sudo[8264]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:38 volumio-16 sudo[8264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:38 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:38 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:38 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:38 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:38 volumio-16 sudo[8274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:38 volumio-16 sudo[8274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:38 volumio-16 sudo[8276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:38 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:38 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 sudo[8221]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 sudo[8264]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:38 volumio-16 sudo[8281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:38 volumio-16 sudo[8257]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:38 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 sudo[8279]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:38 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 sudo[8279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:38 volumio-16 sudo[8279]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:38 volumio-16 sudo[8274]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8276]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:38 volumio-16 mpd[8300]: 2026-08-31T11:47:38 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 11:47:38 volumio-16 systemd[1]: Started mpd.service - Music Player Daemon. Aug 31 11:47:38 volumio-16 sudo[8060]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: error: updateQueue error: null Aug 31 11:47:38 volumio-16 sudo[8227]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8162]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8078]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: error: MPD error: The expression evaluated to a falsy value: Aug 31 11:47:38 volumio-16 volumio[1383]: assert.ok(self.idling) Aug 31 11:47:38 volumio-16 volumio[1383]: error: The expression evaluated to a falsy value: Aug 31 11:47:38 volumio-16 volumio[1383]: assert.ok(self.idling) Aug 31 11:47:38 volumio-16 volumio[1383]: error: MPD error: The expression evaluated to a falsy value: Aug 31 11:47:38 volumio-16 volumio[1383]: assert.ok(self.idling) Aug 31 11:47:38 volumio-16 volumio[1383]: error: The expression evaluated to a falsy value: Aug 31 11:47:38 volumio-16 volumio[1383]: assert.ok(self.idling) Aug 31 11:47:38 volumio-16 volumio[1383]: error: updateQueue error: null Aug 31 11:47:38 volumio-16 qobuz-connect[2951]: 20260831 11:47:38.814 [2951.2951] INFO SampleApp: shat down connection on UNIX socket Aug 31 11:47:38 volumio-16 volumio[1383]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 31 11:47:38 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:38 volumio-16 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 31 11:47:38 volumio-16 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 31 11:47:38 volumio-16 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 31 11:47:38 volumio-16 sudo[8032]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8211]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8133]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8281]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 sudo[8140]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:38 volumio-16 volumio[1383]: info: Executing endpoint qc_getconfig Aug 31 11:47:38 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.868 [8317.8317] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.870 [8317.8317] INFO VolumeManager: [0x1fce260]: Setting new playback volume: 75 Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.871 [8317.8317] INFO VolumeManager: [0x1fce260]: Setting new mute state: 0 Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.871 [8317.8317] INFO AudioStreamManager: [0x1fcdfb8]: Setting new audio download buffer size: 1048576 Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.871 [8317.8317] INFO QobuzConnect: [0x1fceb28]: Client initialized! Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.871 [8317.8317] INFO SampleApp: Starting Avahi advertising, name: Volumio 16, service name: _qobuz-connect._tcp Aug 31 11:47:38 volumio-16 volumio[1383]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 31 11:47:38 volumio-16 volumio[1383]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.876 [8317.8317] INFO LocalConfigManager: [0x1fcdce0]: Starting Local Configuration server Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.876 [8317.8317] INFO SampleApp: Starting Local configuration server Aug 31 11:47:38 volumio-16 qobuz-connect[8317]: 20260831 11:47:38.877 [8317.8317] INFO SampleApp: Connected to UNIX socket client 0x1fb8818 Aug 31 11:47:39 volumio-16 qobuz-connect[8317]: 20260831 11:47:39.018 [8317.8317] INFO SampleApp: Playback volume changed: 75 Aug 31 11:47:39 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 sudo[8332]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 11:47:40 volumio-16 sudo[8332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:40 volumio-16 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 31 11:47:40 volumio-16 sudo[8332]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:40 volumio-16 volumio[1383]: info: Executing endpoint tc_getconfig Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 31 11:47:40 volumio-16 vtcs[8335]: STARTING TidalConnect services, version: 1.6.1 Aug 31 11:47:40 volumio-16 vtcs[8335]: STARTED TidalConnect services. Aug 31 11:47:40 volumio-16 volumio[1383]: info: Executing endpoint tc_connect Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: Connecting to TidalConnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:40 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:47:40 volumio-16 volumio[1383]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::servicePushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:40 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:40 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:40 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:40 volumio-16 volumio[1383]: info: CorePlayQueue::getTrack 0 Aug 31 11:47:40 volumio-16 volumio[1383]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Aug 31 11:47:40 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:40.724Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116296 volume=100 Aug 31 11:47:40 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:40.725Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:40 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:40.725Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116296 volume=100 Aug 31 11:47:40 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:40.725Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 volumio[1383]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:40 volumio-16 sudo[8368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 11:47:40 volumio-16 sudo[8368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:40 volumio-16 sudo[8370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 11:47:40 volumio-16 sudo[8370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:40 volumio-16 sudo[8370]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:40 volumio-16 sudo[8368]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:41 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:41 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:41 volumio-16 volumio[1383]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:41 volumio-16 volumio[1383]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:41 volumio-16 sudo[8384]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 11:47:41 volumio-16 sudo[8384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:41 volumio-16 sudo[8384]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:41 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:41 volumio-16 volumio[1383]: info: TidalConnect service stoped! Aug 31 11:47:41 volumio-16 volumio[1383]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:41 volumio-16 volumio[1383]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 31 11:47:41 volumio-16 sudo[8396]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 31 11:47:41 volumio-16 sudo[8396]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:41 volumio-16 sudo[8396]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreStateMachine::getcurrentVolume Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:41 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:41 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:41 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:41 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:41.519Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116296 volume=100 Aug 31 11:47:41 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:41.519Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:43 volumio-16 volumio[1383]: info: TidalConnect service started! Aug 31 11:47:43 volumio-16 volumio[1383]: info: TidalConnect service started! Aug 31 11:47:43 volumio-16 volumio[1383]: info: TidalConnect service started! Aug 31 11:47:44 volumio-16 volumio[1383]: info: TidalConnect service started! Aug 31 11:47:44 volumio-16 volumio[1383]: info: TidalConnect service started! Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.030Z level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.567211ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.046Z level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.051Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=5.57432ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.068Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=21.805144ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.070Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=24.171931ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.086Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://securetoken.googleapis.com duration=39.780865ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.110Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://google.com duration=64.512375ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.130Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://functions.volumio.cloud duration=84.12687ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.138Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://functions.volumio.cloud duration=91.534231ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.152Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://www.googleapis.com duration=106.439929ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.157Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=http://pushupdates.volumio.org duration=110.886352ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.269Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=http://plugins.volumio.org duration=223.510011ms Aug 31 11:47:45 volumio-16 sudo[8404]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 11:47:45 volumio-16 sudo[8404]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:45 volumio-16 sudo[8404]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:45 volumio-16 sudo[8406]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 11:47:45 volumio-16 sudo[8406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.282Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=236.37594ms Aug 31 11:47:45 volumio-16 sudo[8406]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.307Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=https://database.volumio.cloud duration=261.549867ms Aug 31 11:47:45 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:45.338Z level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" latency=4.124817ms timeout=10s endpoint=http://cddb.volumio.org duration=292.132293ms Aug 31 11:47:45 volumio-16 volumio[1383]: verbose: New Socket.io Connection to 192.168.50.161 from 192.168.50.29 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 14 Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 11:47:45 volumio-16 volumio[1383]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 11:47:45 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:47:45 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: Listing playlists Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 11:47:45 volumio-16 sudo[8410]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 11:47:45 volumio-16 sudo[8410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:45 volumio-16 sudo[8410]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:45 volumio-16 sudo[8412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 11:47:45 volumio-16 sudo[8412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:45 volumio-16 sudo[8412]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:45 volumio-16 volumio[1383]: verbose: New Socket.io Connection to 192.168.50.161 from 192.168.50.29 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 15 Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetVisibleSources Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 31 11:47:45 volumio-16 volumio[1383]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 31 11:47:45 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:47:45 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:45 volumio-16 volumio[1383]: info: Listing playlists Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 11:47:45 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 11:47:47 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:47:47 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:47 volumio-16 volumio[1383]: info: Getting Alsa Cards List without I2S DAC Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 31 11:47:47 volumio-16 volumio[1383]: info: Received Get System Info Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 31 11:47:47 volumio-16 volumio[1383]: info: Discovery: Getting this device information Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:47 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 31 11:47:54 volumio-16 volumio[1383]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 31 11:47:54 volumio-16 volumio[1383]: info: Preparing to save Alsa Options, stopping services first Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPause Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreStateMachine::pause Aug 31 11:47:54 volumio-16 volumio[1383]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI 0 Out"},"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"}} Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:54 volumio-16 volumio[1383]: info: Enabling I2S DAC: Allo DigiOne Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:54 volumio-16 sudo[8448]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 31 11:47:54 volumio-16 sudo[8448]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:54 volumio-16 sudo[8448]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:54 volumio-16 sudo[8452]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r max98357a Aug 31 11:47:54 volumio-16 sudo[8452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:54 volumio-16 sudo[8452]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:54 volumio-16 volumio[1383]: info: Removed overlay max98357a Aug 31 11:47:54 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:54 volumio-16 kernel: OF: ERROR: memory leak, expected refcount 1 instead of 2, of_node_get()/of_node_put() unbalanced - destroy cset entry: attach overlay node /max98357a Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8466]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Aug 31 11:47:55 volumio-16 sudo[8466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2s@a4000/status Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#address-cells Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/#size-cells Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /axi/pcie@1000120000/rp1/i2c@74000/status Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/compatible Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/i2s-controller Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/status Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/clock44-gpio Aug 31 11:47:55 volumio-16 kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc@107c000000/sound/clock48-gpio Aug 31 11:47:55 volumio-16 sudo[8466]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: warn: Unable to locate the device HDMI 0 Out a reboot may be required. Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: Invalid card number '2'. Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 31 11:47:55 volumio-16 volumio[1383]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 31 11:47:55 volumio-16 volumio[1383]: info: Disabling external Volume Control Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 31 11:47:55 volumio-16 kernel: wm8804 1-003b: Failed to read device ID: -121 Aug 31 11:47:55 volumio-16 kernel: wm8804 1-003b: probe with driver wm8804 failed with error -121 Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreStateMachine::pushState Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioPushState Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output update for this device Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Pushing multiroomSync output Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:55 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:55.049Z level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" state=STATUS_PAUSED positionMs=116296 volume=100 Aug 31 11:47:55 volumio-16 volumio5-onboarding[2345]: time=2026-08-31T15:47:55.049Z level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.50.29:50160 @ 0x28ce1e0" id=tidal://song/306947906 title=Flaws Aug 31 11:47:55 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:55 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:55 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:55 volumio-16 volumio[1383]: info: I2S Param [object Object] successfully enabled Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 11:47:55 volumio-16 volumio[1383]: info: Getting Alsa Cards List without I2S DAC Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: Invalid card number '2'. Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 31 11:47:55 volumio-16 volumio[1383]: info: Asound.conf file written Aug 31 11:47:55 volumio-16 sudo[8510]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 11:47:55 volumio-16 sudo[8510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8510]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:55 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:55 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8517]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:55 volumio-16 sudo[8517]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8517]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8521]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:55 volumio-16 sudo[8521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:55 volumio-16 vtcs[8335]: [2026-08-31 11:47:55.172] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Aug 31 11:47:55 volumio-16 vtcs[8335]: [2026-08-31 11:47:55.175] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 11:47:55 volumio-16 sudo[8530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:55 volumio-16 systemd[1]: vtcs.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 31 11:47:55 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:55 volumio-16 sudo[8530]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:55 volumio-16 sudo[8536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:55 volumio-16 sudo[8536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8536]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8540]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:55 volumio-16 sudo[8540]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:55 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:55 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:55 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:55 volumio-16 sudo[8551]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:55 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:55 volumio-16 sudo[8551]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8571]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8571]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8558]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:55 volumio-16 sudo[8558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:55 volumio-16 sudo[8558]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 11:47:55 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:55 volumio-16 volumio[1383]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 11:47:55 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 sudo[8571]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8560]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8580]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8595]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8595]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8563]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8600]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8600]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:55 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:55 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:55 volumio-16 sudo[8601]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8601]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:55 volumio-16 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 qobuz-connect[8317]: 20260831 11:47:55.372 [8317.8317] INFO SampleApp: Stopping Local configuration server Aug 31 11:47:55 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Consumed 1.523s CPU time. Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 sudo[8605]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Asound.conf file written Aug 31 11:47:55 volumio-16 sudo[8607]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8613]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 31 11:47:55 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 sudo[8613]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8593]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8613]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 sudo[8601]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8605]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:55 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi0 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:55 volumio-16 volumio[1383]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 31 11:47:55 volumio-16 volumio[1383]: Found hardware: "vc4-hdmi" "" "" "" "" Aug 31 11:47:55 volumio-16 volumio[1383]: Hardware is initialized using a generic method Aug 31 11:47:55 volumio-16 volumio[1383]: No state is present for card vc4hdmi1 Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:55 volumio-16 sudo[8607]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8621]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:55 volumio-16 sudo[8625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:55 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:55 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8648]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8648]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 volumio[1383]: info: Preparing to generate the ALSA configuration file Aug 31 11:47:55 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:55 volumio-16 sudo[8655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8663]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 31 11:47:55 volumio-16 volumio[1383]: info: Reading ALSA contributions from plugins. Aug 31 11:47:55 volumio-16 volumio[1383]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 11:47:55 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8648]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8655]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8657]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:55 volumio-16 sudo[8657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:55 volumio-16 sudo[8657]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8663]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 sudo[8673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: Asound.conf file unchanged, so no further update is needed Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting MPD Aug 31 11:47:55 volumio-16 sudo[8678]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8678]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8682]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 11:47:55 volumio-16 sudo[8682]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8686]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 11:47:55 volumio-16 sudo[8682]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 volumio[1383]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8680]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8680]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 volumio[1383]: info: QobuzConnect: setDeactiveState invoked Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::volumioGetState Aug 31 11:47:55 volumio-16 sudo[8678]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:55 volumio-16 volumio[1383]: info: Output device has changed, restarting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 systemd[1]: mpd.socket: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 31 11:47:55 volumio-16 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 31 11:47:55 volumio-16 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 31 11:47:55 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:55 volumio-16 sudo[8697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 sudo[8697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 11:47:55 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 sudo[8721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 31 11:47:55 volumio-16 sudo[8721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 volumio[1383]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read properties of undefined (reading 'split') Aug 31 11:47:55 volumio-16 volumio[1383]: info: MPD Permissions set Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8680]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8727]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8727]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8727]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8716]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 31 11:47:55 volumio-16 sudo[8716]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 11:47:55 volumio-16 sudo[8748]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 31 11:47:55 volumio-16 sudo[8748]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8716]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 volumio[1383]: info: Starting Shairport Sync Aug 31 11:47:55 volumio-16 sudo[8753]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8753]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 sudo[8697]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8721]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 sudo[8755]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 11:47:55 volumio-16 sudo[8755]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 31 11:47:55 volumio-16 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 31 11:47:55 volumio-16 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 31 11:47:55 volumio-16 sudo[8753]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 sudo[8755]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:55 volumio-16 volumio[1383]: info: Shairport-Sync Started Aug 31 11:47:55 volumio-16 volumio[1383]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Aug 31 11:47:55 volumio-16 volumio[1383]: info: PLUGIN onReboot : networkfs Aug 31 11:47:55 volumio-16 volumio[1383]: info: PLUGIN onReboot : touch_display Aug 31 11:47:55 volumio-16 sudo[8790]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Aug 31 11:47:55 volumio-16 sudo[8790]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:55 volumio-16 systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Aug 31 11:47:55 volumio-16 startx[7060]: xinit: connection to X server lost Aug 31 11:47:55 volumio-16 startx[7060]: Aug 31 11:47:56 volumio-16 sudo[8797]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Aug 31 11:47:56 volumio-16 sudo[8797]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 31 11:47:56 volumio-16 systemd-logind[1037]: The system will reboot now! Aug 31 11:47:56 volumio-16 systemd-logind[1037]: System is rebooting. Aug 31 11:47:56 volumio-16 mpd[8751]: 2026-08-31T11:47:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 11:47:56 volumio-16 sudo[8600]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8686]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8797]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8625]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8673]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8748]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8521]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8540]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 sudo[8595]: pam_unix(sudo:session): session closed for user root Aug 31 11:47:56 volumio-16 volumio[1383]: error: QobuzConnect: could not execute /bin/systemctl to restart qobuz connect process. Reason: Job for qobuz-connect.service canceled. Aug 31 11:47:56 volumio-16 volumio[1383]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 11:47:56 volumio-16 volumio[1383]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "undefined".] { Aug 31 11:47:56 volumio-16 volumio[1383]: code: 'ERR_UNHANDLED_REJECTION' Aug 31 11:47:56 volumio-16 volumio[1383]: } Aug 31 11:47:56 volumio-16 volumio[1383]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 11:47:56 volumio-16 systemd[1]: Removed slice system-getty.slice - Slice /system/getty. Aug 31 11:47:56 volumio-16 systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target graphical.target - Graphical Interface. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target rpc_pipefs.target. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped target timers.target - Timer Units. Aug 31 11:47:56 volumio-16 systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Aug 31 11:47:56 volumio-16 systemd[1]: apt-daily.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Aug 31 11:47:56 volumio-16 systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Aug 31 11:47:56 volumio-16 systemd[1]: e2scrub_all.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Aug 31 11:47:56 volumio-16 systemd[1]: fstrim.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Aug 31 11:47:56 volumio-16 systemd[1]: man-db.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped man-db.timer - Daily man-db regeneration. Aug 31 11:47:56 volumio-16 systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Aug 31 11:47:56 volumio-16 systemd[1]: setdatetime-helper.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Aug 31 11:47:56 volumio-16 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 31 11:47:56 volumio-16 systemd[1]: systemd-rfkill.socket: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Aug 31 11:47:56 volumio-16 startx[7060]: waiting for X server to shut down Aug 31 11:47:56 volumio-16 startx[7061]: (II) Server terminated successfully (0). Closing log file. Aug 31 11:47:56 volumio-16 systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Aug 31 11:47:56 volumio-16 systemd[1]: Stopping bluealsa.service - BlueALSA service... Aug 31 11:47:56 volumio-16 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 31 11:47:56 volumio-16 systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Aug 31 11:47:56 volumio-16 sshd[2094]: Received signal 15; terminating. Aug 31 11:47:56 volumio-16 bluealsa[1155]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Aug 31 11:47:56 volumio-16 systemd[1]: Stopping ssh.service - OpenBSD Secure Shell server... Aug 31 11:47:56 volumio-16 systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Aug 31 11:47:56 volumio-16 autossh[2816]: received signal to exit (15) Aug 31 11:47:56 volumio-16 systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Aug 31 11:47:56 volumio-16 systemd[1]: Stopping upower.service - Daemon for power management... Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Aug 31 11:47:56 volumio-16 systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Aug 31 11:47:56 volumio-16 systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Aug 31 11:47:56 volumio-16 volumiobt[8818]: INFO [BTSTART] Disconnecting all Bluetooth devices... Aug 31 11:47:56 volumio-16 systemd[1]: bluealsa.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped bluealsa.service - BlueALSA service. Aug 31 11:47:56 volumio-16 systemd[1]: ssh.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped ssh.service - OpenBSD Secure Shell server. Aug 31 11:47:56 volumio-16 systemd[1]: volumio5-onboarding.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Aug 31 11:47:56 volumio-16 systemd[1]: volumio5-onboarding.service: Consumed 4.197s CPU time. Aug 31 11:47:56 volumio-16 systemd[1]: sshtunnel.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Aug 31 11:47:56 volumio-16 systemd[1]: upower.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped upower.service - Daemon for power management. Aug 31 11:47:56 volumio-16 systemd[1]: mpd.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 31 11:47:56 volumio-16 systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Aug 31 11:47:56 volumio-16 systemd[1]: systemd-random-seed.service: Deactivated successfully. Aug 31 11:47:56 volumio-16 systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Adv Monitor app :1.103 disconnected from D-Bus Aug 31 11:47:56 volumio-16 volumiobt[8827]: Attempting to disconnect from 64:A1:5F:31:56:1F Aug 31 11:47:56 volumio-16 volumiobt[8827]: [NEW] Media /org/bluez/hci0 Aug 31 11:47:56 volumio-16 volumiobt[8827]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Aug 31 11:47:56 volumio-16 volumiobt[8827]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Aug 31 11:47:56 volumio-16 volumiobt[8827]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Aug 31 11:47:56 volumio-16 volumiobt[8827]: AdvertisementMonitor path registered Aug 31 11:47:56 volumio-16 bluetoothd[1030]: Path / reserved for Adv Monitor app :1.104 Aug 31 11:47:56 volumio-16 sudo[8830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-31 11:46' Aug 31 11:47:56 volumio-16 sudo[8830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"