Jan 11 18:21:03 volumio ntpd[883]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 11 18:21:11 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: Retrieving Cloud Streaming UI Jan 11 18:21:20 volumio volumio[940]: info: Getting Tidal Cloud Configuration Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: Getting Qobuz Cloud Configuration Jan 11 18:21:20 volumio volumio[940]: info: Asking plugin for UI Config Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: Getting Spotify Cloud Configuration Jan 11 18:21:20 volumio volumio[940]: info: Asking plugin for UI Config Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: Saving Spotify Acccount Jan 11 18:21:20 volumio volumio[940]: info: Got it Jan 11 18:21:20 volumio volumio[940]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 11 18:21:20 volumio volumio[940]: info: Got Tidal Cloud Configuration Jan 11 18:21:20 volumio volumio[940]: info: Got it Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:20 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 11 18:21:24 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:28 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:32 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:33 volumio volumio[940]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jan 11 18:21:33 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jan 11 18:21:33 volumio volumio[940]: info: Saving configuration Jan 11 18:21:33 volumio volumio[940]: info: Done Jan 11 18:21:34 volumio volumio[940]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Jan 11 18:21:34 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Jan 11 18:21:34 volumio volumio[940]: info: Saving configuration Jan 11 18:21:34 volumio volumio[940]: info: Done Jan 11 18:21:36 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:40 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:44 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:48 volumio volumio[940]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Jan 11 18:21:48 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Jan 11 18:21:48 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 11 18:21:48 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:48 volumio volumio[940]: info: CoreCommandRouter::volumioGetBrowseSources Jan 11 18:21:48 volumio volumio[940]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Jan 11 18:21:51 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:21:53 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:53 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:53 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:53 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:53 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:21:53 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:21:57 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 11 18:21:58 volumio volumio[940]: info: browseTIDALUri took 806 milliseconds Jan 11 18:21:58 volumio volumio[940]: info: Preload queue cleared Jan 11 18:21:58 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:02 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 11 18:22:03 volumio volumio[940]: info: browseTIDALUri took 1751 milliseconds Jan 11 18:22:03 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:03 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420297553 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413212339 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/421878433 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418803497 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/422361023 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419523500 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417650241 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420353394 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420717172 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420006427 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419225590 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418092369 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420254339 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417870959 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/423782235 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/421514303 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420579202 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/411555329 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419098230 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420802637 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418377172 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417123647 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416050366 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420047229 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417048908 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417891755 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417112018 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419591586 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/411328222 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/422445413 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419026889 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/421049103 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418796675 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420611117 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/411193414 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417105360 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419036263 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419264613 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419262826 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/420262436 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416287994 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416509845 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415984884 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413001600 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418533384 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415651812 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419214881 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418982028 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/392110918 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414596038 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417153849 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417830679 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415082142 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418783864 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/408027398 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/409675347 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/407849485 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/422223372 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414083087 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/419254583 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416147490 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/407690470 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415505706 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416360412 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417192907 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/412157903 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415838338 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415056081 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416043303 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415712030 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413448340 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415093813 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/412102096 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415983787 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414933036 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/416288114 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414974950 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414156600 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417038852 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/417632972 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413454600 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/409657475 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/412572498 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/418073610 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414754932 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/386737832 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413439639 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/413423511 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/414696597 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/411878776 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/407817556 Jan 11 18:22:03 volumio volumio[940]: info: Preloading song: tidal://song/415551981 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/411576512 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/409764342 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/415534404 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/412832717 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/413294289 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/411410358 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/414022570 Jan 11 18:22:04 volumio volumio[940]: info: Preloading song: tidal://song/415107496 Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420297553 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/413212339 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/421878433 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/418803497 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/422361023 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 241 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/419523500 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/417650241 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420353394 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420717172 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420006427 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/419225590 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 260 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/418092369 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420254339 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/417870959 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/423782235 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 677 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/421514303 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 668 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420579202 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/411555329 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 356 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 717 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: explodeTIDALUri took 671 milliseconds Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/419098230 in service tidal Jan 11 18:22:04 volumio volumio[940]: info: Exploding uri tidal://song/420802637 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 664 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/418377172 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/417123647 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 746 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/416050366 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 346 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 269 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 274 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 736 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/420047229 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 264 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 724 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/417048908 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 256 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 239 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 704 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/417891755 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/417112018 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: browseTIDALUri took 756 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 718 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 235 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 701 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/419591586 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240945 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240946 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240947 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240948 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240949 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240950 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240951 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240952 Jan 11 18:22:05 volumio volumio[940]: info: Preloading song: tidal://song/439240953 Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 247 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 707 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 233 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240945 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 238 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240946 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 241 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240947 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240948 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 262 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:05 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240949 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 236 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 637 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240950 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240951 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 247 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 232 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240952 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: Exploding uri tidal://song/439240953 in service tidal Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 256 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 239 milliseconds Jan 11 18:22:05 volumio volumio[940]: info: explodeTIDALUri took 237 milliseconds Jan 11 18:22:06 volumio volumio[940]: info: explodeTIDALUri took 236 milliseconds Jan 11 18:22:06 volumio volumio[940]: info: explodeTIDALUri took 239 milliseconds Jan 11 18:22:06 volumio volumio[940]: info: explodeTIDALUri took 238 milliseconds Jan 11 18:22:06 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:06 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:06 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:06 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:11 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jan 11 18:22:12 volumio volumio[940]: info: browseTIDALUri took 697 milliseconds Jan 11 18:22:12 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720094 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720095 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720096 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720097 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720098 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720099 Jan 11 18:22:12 volumio volumio[940]: info: Preloading song: tidal://song/464720100 Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720094 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720095 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720096 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarAlbums Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720097 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720098 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: explodeTIDALUri took 234 milliseconds Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720099 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Exploding uri tidal://song/464720100 in service tidal Jan 11 18:22:12 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:12 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:12 volumio volumio[940]: info: explodeTIDALUri took 280 milliseconds Jan 11 18:22:12 volumio volumio[940]: info: explodeTIDALUri took 648 milliseconds Jan 11 18:22:13 volumio volumio[940]: info: explodeTIDALUri took 632 milliseconds Jan 11 18:22:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:13 volumio volumio[940]: info: explodeTIDALUri took 648 milliseconds Jan 11 18:22:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:13 volumio volumio[940]: info: explodeTIDALUri took 675 milliseconds Jan 11 18:22:13 volumio volumio[940]: info: explodeTIDALUri took 692 milliseconds Jan 11 18:22:13 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:13 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:14 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::ClearQueue Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::stop Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::stPlaybackTimer Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::updateTrackBlock Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::getTrackBlock Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::getTrack 1 Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:14 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:14 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::getTrack 1 Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::serviceStop Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::getTrack 1 Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::serviceStop Jan 11 18:22:14 volumio volumio[940]: info: [1768123334932] ControllerTidal::stop Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 18:22:14 volumio volumio[940]: info: ControllerMpd::stop Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::clearPlayQueue Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::saveQueue Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::volumioPushQueue Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::addQueueItems Jan 11 18:22:14 volumio volumio[940]: info: CorePlayQueue::addQueueItems Jan 11 18:22:14 volumio volumio[940]: info: Preload queue cleared Jan 11 18:22:14 volumio volumio[940]: info: Adding Item to queue: tidal://album/464720093 Jan 11 18:22:14 volumio volumio[940]: info: Exploding uri tidal://album/464720093 in service tidal Jan 11 18:22:14 volumio volumio[940]: info: Jan 11 18:22:14 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:14 volumio volumio[940]: info: sendMpdCommand stop took 55 milliseconds Jan 11 18:22:14 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:14 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:14 volumio volumio[940]: info: Jan 11 18:22:14 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:14 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:14 volumio volumio[940]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 18:22:14 volumio volumio[940]: info: sendMpdCommand status took 2 milliseconds Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:14 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:14 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:14 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:14 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 1 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126802~ZDk3OWU4YzRjZmM5ZmQwYzE5MjMxZTM5ZDlmYmRjMDM3NTRlN2I2Ng==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5MWFhY2YxZmJmZjk3NmM3OGRiM2NmNmYyNWQ2YjE2Yy5tcDQ/0.flac?token=1768126802~ZDk3OWU4YzRjZmM5ZmQwYzE5MjMxZTM5ZDlmYmRjMDM3NTRlN2I2Ng==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 1 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService stop Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: No code Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 85ms Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand playlistinfo took 80 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 1 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126802~ZDk3OWU4YzRjZmM5ZmQwYzE5MjMxZTM5ZDlmYmRjMDM3NTRlN2I2Ng==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5MWFhY2YxZmJmZjk3NmM3OGRiM2NmNmYyNWQ2YjE2Yy5tcDQ/0.flac?token=1768126802~ZDk3OWU4YzRjZmM5ZmQwYzE5MjMxZTM5ZDlmYmRjMDM3NTRlN2I2Ng==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 1 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService stop Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: No code Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 155ms Jan 11 18:22:15 volumio volumio[940]: info: explodeTIDALUri took 267 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushQueue Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::saveQueue Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::updateTrackBlock Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrackBlock Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPlay Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::play index 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::stop Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::play index undefined Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::startPlaybackTimer Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: info: [1768123335248] ControllerTidal::clearAddPlayTrack Jan 11 18:22:15 volumio volumio[940]: info: Getting stream with soundQuality LOSSLESS Jan 11 18:22:15 volumio volumio[940]: info: getStreamUrl took 267 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand stop Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand stop took 2 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand clear Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces system playlist update Jan 11 18:22:15 volumio volumio[940]: info: Ignoring MPD Status Update Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand clear took 2 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==" Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces system playlist update Jan 11 18:22:15 volumio volumio[940]: info: Ignoring MPD Status Update Jan 11 18:22:15 volumio volumio[940]: error: updateQueue error: null Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces system playlist update Jan 11 18:22:15 volumio volumio[940]: info: Ignoring MPD Status Update Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 5ms Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==" took 4 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 3ms Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand play Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces system playlist update Jan 11 18:22:15 volumio volumio[940]: info: Ignoring MPD Status Update Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 5ms Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand play took 3 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 2ms Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand status took 5 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:15 volumio volumio[940]: info: Jan 11 18:22:15 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand status took 7 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand status took 3 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus stop Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Jan 11 18:22:15 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 51ms Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 98ms Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand playlistinfo took 109 milliseconds Jan 11 18:22:15 volumio volumio[940]: info: sendMpdCommand playlistinfo took 108 milliseconds Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:15 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Jan 11 18:22:15 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:15 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:15 volumio volumio[940]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:15 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState stateService play Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus play Jan 11 18:22:15 volumio volumio[940]: info: Received an update from plugin. extracting info from payload Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:15 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:15 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 204ms Jan 11 18:22:15 volumio volumio[940]: info: ------------------------------ 198ms Jan 11 18:22:17 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:17 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:17 volumio volumio[940]: info: Executing endpoint metavolumio Jan 11 18:22:17 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 11 18:22:25 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:26 volumio volumio[940]: info: Getting Alsa Cards List without I2S DAC Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 18:22:26 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 18:22:38 volumio volumio[940]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 11 18:22:38 volumio volumio[940]: info: Preparing to save Alsa Options, stopping services first Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioPause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::stPlaybackTimer Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::servicePause Jan 11 18:22:38 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::servicePause Jan 11 18:22:38 volumio volumio[940]: info: [1768123358577] ControllerTidal::pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 11 18:22:38 volumio volumio[940]: info: ControllerMpd::pause Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand pause Jan 11 18:22:38 volumio volumio[940]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"Generic I2S DAC"}} Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:38 volumio volumio[940]: info: Device 2 does not have any Mixer Control Available Jan 11 18:22:38 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:38 volumio vtcs[1919]: [2026-01-11 18:22:38.709] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 11 18:22:38 volumio vtcs[1919]: [2026-01-11 18:22:38.709] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 11 18:22:38 volumio vtcs[1919]: [2026-01-11 18:22:38.710] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Jan 11 18:22:38 volumio sudo[2962]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:38 volumio sudo[2962]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 18:22:38 volumio volumio[940]: info: Updating Volume Controller Parameters: Device: 2 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 18:22:38 volumio volumio[940]: info: Disabling external Volume Control Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:38 volumio volumio[940]: info: Preparing to generate the ALSA configuration file Jan 11 18:22:38 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 11 18:22:38 volumio systemd[1]: vtcs.service: Killing process 1923 (vtcs) with signal SIGKILL. Jan 11 18:22:38 volumio systemd[1]: vtcs.service: Deactivated successfully. Jan 11 18:22:38 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 11 18:22:38 volumio sudo[2962]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:38 volumio sudo[2969]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:38 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:38 volumio volumio[940]: info: Jan 11 18:22:38 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:38 volumio volumio[940]: info: sendMpdCommand pause took 340 milliseconds Jan 11 18:22:38 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:38 volumio volumio[940]: info: Jan 11 18:22:38 volumio volumio[940]: ---------------------------- MPD announces state update: player Jan 11 18:22:38 volumio volumio[940]: info: ControllerMpd::getState Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand status Jan 11 18:22:38 volumio volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 11 18:22:38 volumio volumio[940]: info: Reading ALSA contributions from plugins. Jan 11 18:22:38 volumio volumio[940]: info: sendMpdCommand status took 19 milliseconds Jan 11 18:22:38 volumio volumio[940]: info: sendMpdCommand status took 18 milliseconds Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::parseState Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 18:22:38 volumio volumio[940]: info: sendMpdCommand playlistinfo took 5 milliseconds Jan 11 18:22:38 volumio volumio[940]: info: sendMpdCommand playlistinfo took 3 milliseconds Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:38 volumio volumio[940]: verbose: ControllerMpd::parseTrackInfo Jan 11 18:22:38 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:38 volumio sudo[2976]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:38 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:38 volumio volumio[940]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24598,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"867 Kbps","isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:38 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::syncState stateService pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:38 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::stPlaybackTimer Jan 11 18:22:38 volumio volumio[940]: info: ControllerMpd::pushState Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:38 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:38 volumio volumio[940]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24598,"duration":123,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"867 Kbps","isStreaming":false,"title":"0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ5YmZiNzNmMGJmY2NkMmQyYWIyZjQzOTEyM2YxMzRjYy5tcDQ/0.flac?token=1768126935~NmFhNWM0YTE3MTMzNmMyNDBlY2YwYjIyMDZmOTc5MWQ5MzFhYmQwMw==","trackType":"tidal"} Jan 11 18:22:38 volumio volumio[940]: verbose: CURRENT POSITION 0 Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::syncState stateService pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::syncState currentStatus pause Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:38 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:38 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:38 volumio volumio[940]: info: CoreStateMachine::stPlaybackTimer Jan 11 18:22:38 volumio volumio[940]: info: ------------------------------ 75ms Jan 11 18:22:38 volumio volumio[940]: info: ------------------------------ 72ms Jan 11 18:22:38 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:39 volumio volumio[940]: info: Getting Alsa Cards List without I2S DAC Jan 11 18:22:39 volumio sudo[2983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 18:22:39 volumio sudo[2983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:39 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 11 18:22:39 volumio qobuz-connect[1862]: 20260111 18:22:39.126 [1862.1862] INFO SampleApp: Stopping Local configuration server Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 18:22:39 volumio volumio[940]: info: Asound.conf file unchanged, so no further update is needed Jan 11 18:22:39 volumio volumio[940]: info: Output device has changed, restarting MPD Jan 11 18:22:39 volumio volumio[940]: info: Output device has changed, restarting Shairport Sync Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:39 volumio sudo[2997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 18:22:39 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio sudo[2999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 18:22:39 volumio sudo[2999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 18:22:39 volumio volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:39 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 18:22:39 volumio sudo[3009]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:39 volumio sudo[3009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio sudo[3011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:39 volumio sudo[3011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio volumio[940]: info: MPD Permissions set Jan 11 18:22:39 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 18:22:39 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 18:22:39 volumio systemd[1]: mpd.service: Consumed 11.485s CPU time. Jan 11 18:22:39 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 18:22:39 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio sudo[3019]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 18:22:39 volumio sudo[3019]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:39 volumio volumio[940]: info: Starting Shairport Sync Jan 11 18:22:39 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 18:22:39 volumio qobuz-connect[1862]: 20260111 18:22:39.543 [1862.1862] INFO SampleApp: shat down connection on UNIX socket Jan 11 18:22:39 volumio volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 11 18:22:39 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:39 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 18:22:39 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 11 18:22:39 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:39 volumio sudo[3029]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 18:22:39 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio sudo[3019]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio sudo[3032]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 18:22:39 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:39 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:39 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 18:22:39 volumio sudo[2983]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 18:22:39 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 18:22:39 volumio systemd[1]: shairport-sync.service: Consumed 3.154s CPU time. Jan 11 18:22:39 volumio sudo[3028]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 18:22:39 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 18:22:39 volumio volumio[940]: info: Executing endpoint qc_getconfig Jan 11 18:22:39 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 18:22:39 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.862 [3033.3033] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 18:22:39 volumio volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 18:22:39 volumio volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.870 [3033.3033] INFO VolumeManager: [0x822e18]: Setting new playback volume: 75 Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.871 [3033.3033] INFO VolumeManager: [0x822e18]: Setting new mute state: 0 Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.871 [3033.3033] INFO QobuzConnect: [0x8237e8]: Client initialized! Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.871 [3033.3033] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.891 [3033.3033] INFO LocalConfigManager: [0x8226f8]: Starting Local Configuration server Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.892 [3033.3033] INFO SampleApp: Starting Local configuration server Jan 11 18:22:39 volumio qobuz-connect[3033]: 20260111 18:22:39.892 [3033.3033] INFO SampleApp: Connected to UNIX socket client 0x80d818 Jan 11 18:22:39 volumio sudo[3009]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:39 volumio sudo[3011]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:40 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 11 18:22:40 volumio qobuz-connect[3033]: 20260111 18:22:40.003 [3033.3033] INFO SampleApp: Stopping Local configuration server Jan 11 18:22:40 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 18:22:40 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:40 volumio volumio[940]: info: Shairport-Sync Started Jan 11 18:22:41 volumio volumio[940]: info: TidalConnect service stoped! Jan 11 18:22:41 volumio qobuz-connect[3033]: 20260111 18:22:41.905 [3033.3033] INFO SampleApp: shat down connection on UNIX socket Jan 11 18:22:41 volumio volumio[940]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Jan 11 18:22:41 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:41 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:41 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 11 18:22:41 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:41 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:41 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:42 volumio volumio[940]: info: TidalConnect service stoped! Jan 11 18:22:42 volumio volumio[940]: info: Executing endpoint qc_getconfig Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.114 [3073.3073] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 11 18:22:42 volumio volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 18:22:42 volumio volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 18:22:42 volumio volumio[940]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Jan 11 18:22:42 volumio volumio[940]: info: QobuzConnect: QOBUZ Connect daemon connected Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.132 [3073.3073] INFO VolumeManager: [0x1175e18]: Setting new playback volume: 75 Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.132 [3073.3073] INFO VolumeManager: [0x1175e18]: Setting new mute state: 0 Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.132 [3073.3073] INFO QobuzConnect: [0x11767e8]: Client initialized! Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.132 [3073.3073] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.161 [3073.3073] INFO LocalConfigManager: [0x11756f8]: Starting Local Configuration server Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.161 [3073.3073] INFO SampleApp: Starting Local configuration server Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.162 [3073.3073] INFO SampleApp: Connected to UNIX socket client 0x1160818 Jan 11 18:22:42 volumio sudo[3086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 18:22:42 volumio sudo[3086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:42 volumio qobuz-connect[3073]: 20260111 18:22:42.264 [3073.3073] INFO SampleApp: Playback volume changed: 75 Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Jan 11 18:22:42 volumio sudo[3086]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:42 volumio volumio[940]: info: Executing endpoint tc_getconfig Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 11 18:22:42 volumio vtcs[3091]: STARTING TidalConnect services, version: 1.5.2.56 Jan 11 18:22:42 volumio vtcs[3091]: STARTED TidalConnect services. Jan 11 18:22:42 volumio volumio[940]: info: Executing endpoint tc_connect Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 11 18:22:42 volumio volumio[940]: info: Connecting to TidalConnect Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:42 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:42 volumio volumio[940]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::servicePushState Jan 11 18:22:42 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio volumio[940]: info: CorePlayQueue::getTrack 0 Jan 11 18:22:42 volumio volumio[940]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Jan 11 18:22:42 volumio volumio[940]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jan 11 18:22:42 volumio volumio[940]: info: Preparing to save Alsa Options, stopping services first Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioPause Jan 11 18:22:42 volumio volumio[940]: info: CoreStateMachine::pause Jan 11 18:22:42 volumio volumio[940]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"Generic I2S DAC"}} Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:42 volumio mpd[3039]: 2026-01-11T18:22:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 18:22:42 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jan 11 18:22:42 volumio sudo[2999]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:42 volumio volumio[940]: info: Device 2 does not have any Mixer Control Available Jan 11 18:22:42 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio vtcs[3091]: [2026-01-11 18:22:42.864] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=5 errorno=104 error=Connection reset by peer Jan 11 18:22:42 volumio vtcs[3091]: [2026-01-11 18:22:42.865] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Jan 11 18:22:42 volumio vtcs[3091]: [2026-01-11 18:22:42.865] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Jan 11 18:22:42 volumio sudo[3119]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:42 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 18:22:42 volumio volumio[940]: info: Updating Volume Controller Parameters: Device: 2 Name: Generic I2S DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jan 11 18:22:42 volumio volumio[940]: info: Disabling external Volume Control Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 18:22:42 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:42 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:42 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:42 volumio volumio[940]: info: Preparing to generate the ALSA configuration file Jan 11 18:22:42 volumio systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Jan 11 18:22:42 volumio systemd[1]: vtcs.service: Deactivated successfully. Jan 11 18:22:42 volumio systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Jan 11 18:22:43 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:43 volumio sudo[3125]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 11 18:22:43 volumio volumio[940]: info: CoreStateMachine::getcurrentVolume Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 18:22:43 volumio volumio[940]: info: CoreStateMachine::pushState Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::volumioPushState Jan 11 18:22:43 volumio volumio[940]: info: MRS: Pushing multiroomSync output update for this device Jan 11 18:22:43 volumio volumio[940]: info: MRS: Pushing multiroomSync output Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:43 volumio sudo[3133]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 18:22:43 volumio volumio[940]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jan 11 18:22:43 volumio volumio[940]: info: Reading ALSA contributions from plugins. Jan 11 18:22:43 volumio sudo[3133]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: TidalConnect service stoped! Jan 11 18:22:43 volumio sudo[3125]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio volumio[940]: info: TidalConnect service stoped! Jan 11 18:22:43 volumio volumio[940]: error: updateQueue error: null Jan 11 18:22:43 volumio sudo[3133]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 18:22:43 volumio sudo[3140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 18:22:43 volumio volumio[940]: info: Getting Alsa Cards List without I2S DAC Jan 11 18:22:43 volumio systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jan 11 18:22:43 volumio qobuz-connect[3073]: 20260111 18:22:43.284 [3073.3073] INFO SampleApp: Stopping Local configuration server Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 11 18:22:43 volumio volumio[940]: info: Asound.conf file unchanged, so no further update is needed Jan 11 18:22:43 volumio volumio[940]: info: Output device has changed, restarting MPD Jan 11 18:22:43 volumio volumio[940]: info: Output device has changed, restarting Shairport Sync Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:43 volumio sudo[3159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 18:22:43 volumio sudo[3159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio sudo[3159]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 18:22:43 volumio sudo[3161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 11 18:22:43 volumio volumio[940]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: QobuzConnect: setDeactiveState invoked Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::volumioGetState Jan 11 18:22:43 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 11 18:22:43 volumio sudo[3171]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:43 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio sudo[3174]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 11 18:22:43 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: MPD Permissions set Jan 11 18:22:43 volumio volumio[940]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 11 18:22:43 volumio volumio[940]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 11 18:22:43 volumio systemd[1]: mpd.service: Deactivated successfully. Jan 11 18:22:43 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 11 18:22:43 volumio systemd[1]: mpd.service: Consumed 3.183s CPU time. Jan 11 18:22:43 volumio systemd[1]: mpd.socket: Deactivated successfully. Jan 11 18:22:43 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 11 18:22:43 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 18:22:43 volumio volumio[940]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 18:22:43 volumio sudo[3185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 11 18:22:43 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jan 11 18:22:43 volumio sudo[3181]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 11 18:22:43 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio sudo[3181]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio volumio[940]: info: Starting Shairport Sync Jan 11 18:22:43 volumio sudo[3194]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 11 18:22:43 volumio sudo[3194]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio sudo[3181]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:43 volumio sudo[3196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 11 18:22:43 volumio volumio[940]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:22:43 volumio sudo[3196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 18:22:43 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jan 11 18:22:43 volumio volumio[940]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Jan 11 18:22:43 volumio volumio[940]: Job for vtcs.service canceled. Jan 11 18:22:43 volumio volumio[940]: at ChildProcess.exithandler (node:child_process:421:12) Jan 11 18:22:43 volumio volumio[940]: at ChildProcess.emit (node:events:514:28) Jan 11 18:22:43 volumio volumio[940]: at maybeClose (node:internal/child_process:1105:16) Jan 11 18:22:43 volumio volumio[940]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) { Jan 11 18:22:43 volumio volumio[940]: code: 1, Jan 11 18:22:43 volumio volumio[940]: killed: false, Jan 11 18:22:43 volumio volumio[940]: signal: null, Jan 11 18:22:43 volumio volumio[940]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Jan 11 18:22:43 volumio volumio[940]: stdout: '', Jan 11 18:22:43 volumio volumio[940]: stderr: 'Job for vtcs.service canceled.\n' Jan 11 18:22:43 volumio volumio[940]: } Jan 11 18:22:43 volumio volumio[940]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 18:22:43 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jan 11 18:22:43 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 18:22:43 volumio systemd[1]: shairport-sync.service: Consumed 1.686s CPU time. Jan 11 18:22:43 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:44 volumio sudo[3190]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 11 18:22:44 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 11 18:22:44 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:44 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jan 11 18:22:44 volumio sudo[3194]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:44 volumio qobuz-connect[3073]: 20260111 18:22:44.171 [3073.3073] INFO SampleApp: shat down connection on UNIX socket Jan 11 18:22:44 volumio systemd[1]: qobuz-connect.service: Deactivated successfully. Jan 11 18:22:44 volumio systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:44 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 11 18:22:44 volumio sudo[3196]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:44 volumio sudo[3140]: pam_unix(sudo:session): session closed for user root Jan 11 18:22:44 volumio sudo[3232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 18:21' Jan 11 18:22:44 volumio sudo[3232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"