Apr 12 17:39:00 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetQueue Apr 12 17:39:00 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::getQueue Apr 12 17:39:00 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getQueue Apr 12 17:39:02 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 12 17:39:03 volumio-streamer-pi volumio[1225]: info: browseTIDALUri took 605 milliseconds Apr 12 17:39:03 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:03 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:05 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Apr 12 17:39:07 volumio-streamer-pi volumio[1225]: info: browseTIDALUri took 2163 milliseconds Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/80654035 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/76239199 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/165550428 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/95648369 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/95648379 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/16859240 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130565 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/36738318 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130689 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/17825564 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68633518 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/13197260 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68683489 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/128534 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55163392 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77611100 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130784 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130787 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130778 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/84706956 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/491706001 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/51070814 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/54180477 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/20750658 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/162033547 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77637865 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130569 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/3268251 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68680282 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77814972 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55391794 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/1290298 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/25508732 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/141415 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/93198800 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/302876 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68683492 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/560272 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/120683729 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77616122 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55130777 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/35986110 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/102559492 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/97872031 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/97872061 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55163303 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/88766486 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/233177383 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/481735 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/16953801 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/35986151 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68684992 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68684992 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68685562 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/1669673 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/35244574 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/544383 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/540307 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/33695192 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/15315191 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/1274617 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/35986120 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68680671 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/13303051 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77616129 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77616137 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/95648363 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77611091 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68707137 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77814975 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/76239205 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/35986159 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/358225322 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/97872048 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55163320 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/317887854 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/82293297 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/458266557 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/73634173 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/600489 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/38026975 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/196665152 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77814671 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/23653054 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/569269 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77670769 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/94440193 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55391795 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/68737210 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/78123338 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/162033536 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/84706951 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/233361 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/82883 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/55391788 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77646269 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/77814676 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/134187363 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/5120096 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Preloading song: tidal://mymusic/tracks/az/5120096 Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/80654035 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/76239199 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/165550428 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/95648369 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/95648379 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/16859240 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 169 milliseconds Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130565 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/36738318 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130689 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/17825564 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 261 milliseconds Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68633518 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/13197260 in service tidal Apr 12 17:39:08 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68683489 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/128534 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55163392 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 333 milliseconds Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77611100 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130784 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130787 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130778 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/84706956 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/491706001 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/51070814 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/54180477 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/20750658 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/162033547 in service tidal Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 665 milliseconds Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 1281 milliseconds Apr 12 17:39:09 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 1276 milliseconds Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77637865 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130569 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/3268251 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68680282 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77814972 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55391794 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/1290298 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/25508732 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/141415 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/93198800 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/302876 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68683492 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/560272 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/120683729 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77616122 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55130777 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35986110 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/102559492 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/97872031 in service tidal Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 1273 milliseconds Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 1259 milliseconds Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 1272 milliseconds Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2292 milliseconds Apr 12 17:39:10 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2332 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/97872061 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55163303 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/88766486 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/233177383 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/481735 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/16953801 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35986151 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68684992 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68684992 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68685562 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/1669673 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35244574 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/544383 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/540307 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/33695192 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/15315191 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/1274617 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35986120 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68680671 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/13303051 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77616129 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77616137 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/95648363 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77611091 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68707137 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77814975 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/76239205 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35986159 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/358225322 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/97872048 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55163320 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/317887854 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/82293297 in service tidal Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2092 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2151 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2152 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2141 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2191 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 4022 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 4022 milliseconds Apr 12 17:39:12 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 4045 milliseconds Apr 12 17:39:14 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/458266557 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/73634173 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/600489 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/38026975 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/196665152 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77814671 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/23653054 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/569269 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/5120096 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77670769 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/94440193 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55391795 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/68737210 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/78123338 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/162033536 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/84706951 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/233361 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/82883 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/55391788 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77646269 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/77814676 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/134187363 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/5120096 in service tidal Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3060 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3059 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3084 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3167 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3139 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3124 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3169 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 3187 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 6512 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 6510 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 6513 milliseconds Apr 12 17:39:15 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 6527 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2499 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2551 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2560 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2527 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2580 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2672 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2671 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2678 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2581 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2623 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2662 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 2697 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8231 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8284 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8302 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8279 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8293 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8325 milliseconds Apr 12 17:39:17 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8351 milliseconds Apr 12 17:39:20 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10388 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10411 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10441 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10444 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10454 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10447 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10483 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10472 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10484 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10450 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10465 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10481 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10495 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 10484 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8947 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/68684992 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8969 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/68685562 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8991 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/1669673 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8974 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/35244574 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8997 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/544383 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8991 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/540307 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9007 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/33695192 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9018 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/15315191 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9025 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/1274617 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9011 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/35986120 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9025 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/68680671 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9062 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/13303051 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9068 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/77616129 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9056 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/77616137 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9069 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/95648363 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9069 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/77611091 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9040 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/68707137 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9043 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/77814975 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9062 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/76239205 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9052 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/35986159 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9076 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/358225322 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9058 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/97872048 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9070 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/55163320 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9084 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/317887854 from service tidal: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 9090 milliseconds Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: TIDAL Browse Error: No response received Apr 12 17:39:21 volumio-streamer-pi volumio[1225]: error: Commandrouter: Cannot explode uri tidal://mymusic/tracks/az/82293297 from service tidal: No response received Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8322 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8342 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8345 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8352 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8419 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8406 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8414 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8419 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8429 milliseconds Apr 12 17:39:23 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8435 milliseconds Apr 12 17:39:24 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 8775 milliseconds Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::ClearQueue Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stop Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::clearPlayQueue Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::saveQueue Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushQueue Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::addQueueItems Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::addQueueItems Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: Adding Item to queue: tidal://mymusic/tracks/az/1290300 Apr 12 17:39:45 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/1290300 in service tidal Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 284 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushQueue Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::saveQueue Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::updateTrackBlock Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrackBlock Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPlay Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::play index 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stop Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::play index undefined Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::startPlaybackTimer Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: [1776040786214] ControllerTidal::clearAddPlayTrack Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Getting stream with soundQuality HI_RES Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: getStreamUrl took 134 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand stop took 2 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces system playlist update Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Ignoring MPD Status Update Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand clear took 3 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiYWU4OWRkMTBlMjFlNDdlNzNlZWE3MGYzZGQ2MmVmY182Mi5tcDQ/0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==" Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: error: updateQueue error: null Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces system playlist update Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Ignoring MPD Status Update Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ------------------------------ 8ms Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiYWU4OWRkMTBlMjFlNDdlNzNlZWE3MGYzZGQ2MmVmY182Mi5tcDQ/0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==" took 5 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand play Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ------------------------------ 7ms Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand play took 4 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:39:46 volumio-streamer-pi kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 21 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 8 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiYWU4OWRkMTBlMjFlNDdlNzNlZWE3MGYzZGQ2MmVmY182Mi5tcDQ/0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","trackType":"tidal"} Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService play Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ------------------------------ 44ms Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":302,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiYWU4OWRkMTBlMjFlNDdlNzNlZWE3MGYzZGQ2MmVmY182Mi5tcDQ/0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","trackType":"tidal"} Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService play Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus play Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: Received an update from plugin. extracting info from payload Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:39:46 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:39:46.529-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:39:46 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:39:46.531-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:39:46 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:39:46.534-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:39:46 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:39:46.536-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:39:46 volumio-streamer-pi volumio[1225]: info: ------------------------------ 86ms Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::ClearQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stPlaybackTimer Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::updateTrackBlock Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrackBlock Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::serviceStop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::serviceStop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: [1776040803276] ControllerTidal::stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::clearPlayQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::saveQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::addQueueItems Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::addQueueItems Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Preload queue cleared Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Adding Item to queue: tidal://mymusic/tracks/az/35986155 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Exploding uri tidal://mymusic/tracks/az/35986155 in service tidal Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.289-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_STOPPED positionMs=0 volume=100 Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.290-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand stop took 58 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 1 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: 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=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiYWU4OWRkMTBlMjFlNDdlNzNlZWE3MGYzZGQ2MmVmY182Mi5tcDQ/0.flac?token=1776044386~NWMxZGZlZjE0ZDk1M2IxYzAyOWM4MDVkOGYyYzY0NmEwM2E3NWJjNA==","trackType":"tidal"} Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: No code Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.372-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.372-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.375-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.377-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.378-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:40:03 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:03.379-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/1290300 title="Who's Crying Now (2024 Remaster)" Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ------------------------------ 63ms Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: explodeTIDALUri took 456 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::saveQueue Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::updateTrackBlock Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrackBlock Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPlay Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::play index 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::play index undefined Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::startPlaybackTimer Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: [1776040803773] ControllerTidal::clearAddPlayTrack Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Getting stream with soundQuality HI_RES Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: getStreamUrl took 153 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand stop took 1 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand clear Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces system playlist update Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Ignoring MPD Status Update Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand clear took 1 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwMTkzY2U3NWFhN2ZkMWE1MmFiNTkzMGUwODc3ZWU4Y182Mi5tcDQ/0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==" Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: error: updateQueue error: null Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces system playlist update Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Ignoring MPD Status Update Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ------------------------------ 3ms Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwMTkzY2U3NWFhN2ZkMWE1MmFiNTkzMGUwODc3ZWU4Y182Mi5tcDQ/0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==" took 2 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand play Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ------------------------------ 4ms Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand play took 2 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 5 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 1 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwMTkzY2U3NWFhN2ZkMWE1MmFiNTkzMGUwODc3ZWU4Y182Mi5tcDQ/0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","trackType":"tidal"} Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService play Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus stop Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ------------------------------ 12ms Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":325,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwMTkzY2U3NWFhN2ZkMWE1MmFiNTkzMGUwODc3ZWU4Y182Mi5tcDQ/0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","trackType":"tidal"} Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService play Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus play Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: Received an update from plugin. extracting info from payload Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:40:03 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:04 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:04.031-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:40:04 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:04.035-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=0 volume=100 Apr 12 17:40:04 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:04.036-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:04 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:04.036-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:04 volumio-streamer-pi volumio[1225]: info: ------------------------------ 64ms Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Retrieving Cloud Streaming UI Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Getting Tidal Cloud Configuration Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Getting Qobuz Cloud Configuration Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Asking plugin for UI Config Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Getting Spotify Cloud Configuration Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Asking plugin for UI Config Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Saving Spotify Acccount Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Got it Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Got Tidal Cloud Configuration Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: Got it Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 17:40:12 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: Getting Alsa Cards List without I2S DAC Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 17:40:17 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Preparing to save Alsa Options, stopping services first Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stPlaybackTimer Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::servicePause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: [1776040836474] ControllerTidal::pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Device 3 does not have any Mixer Control Available Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:36 volumio-streamer-pi vtcs[2329]: [2026-04-12 17:40:36.554] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 12 17:40:36 volumio-streamer-pi vtcs[2329]: [2026-04-12 17:40:36.554] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Disabling external Volume Control Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:36 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:36.618-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PLAYING positionMs=32580 volume=100 Apr 12 17:40:36 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:36.619-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Preparing to generate the ALSA configuration file Apr 12 17:40:36 volumio-streamer-pi sudo[2696]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:36 volumio-streamer-pi sudo[2696]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:36 volumio-streamer-pi sudo[2702]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:36 volumio-streamer-pi sudo[2702]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:36 volumio-streamer-pi systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 12 17:40:36 volumio-streamer-pi systemd[1]: vtcs.service: Deactivated successfully. Apr 12 17:40:36 volumio-streamer-pi systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 12 17:40:36 volumio-streamer-pi sudo[2696]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Reading ALSA contributions from plugins. Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: ---------------------------- MPD announces state update: player Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: sendMpdCommand pause took 244 milliseconds Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: ControllerMpd::getState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand status Apr 12 17:40:36 volumio-streamer-pi sudo[2709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:36 volumio-streamer-pi sudo[2709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: sendMpdCommand status took 22 milliseconds Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: ControllerMpd::parseTrackInfo Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: ControllerMpd::pushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33992,"duration":325,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2854 Kbps","isStreaming":false,"title":"0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEicwMTkzY2U3NWFhN2ZkMWE1MmFiNTkzMGUwODc3ZWU4Y182Mi5tcDQ/0.flac?token=1776044403~NjU5NzhiMjUyMzJmZTg5MjE0N2MzZTgwM2JjZDVlOGNlMGMxNDFmMA==","trackType":"tidal"} Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: verbose: CURRENT POSITION 0 Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState stateService pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::syncState currentStatus pause Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::stPlaybackTimer Apr 12 17:40:36 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:36.786-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:36 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:36.787-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: ------------------------------ 68ms Apr 12 17:40:36 volumio-streamer-pi sudo[2702]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:36 volumio-streamer-pi sudo[2709]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:36 volumio-streamer-pi sudo[2715]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Getting Alsa Cards List without I2S DAC Apr 12 17:40:36 volumio-streamer-pi sudo[2715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:36 volumio-streamer-pi systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 17:40:36 volumio-streamer-pi qobuz-connect[2289]: 20260412 17:40:36.881 [2289.2289] INFO SampleApp: Stopping Local configuration server Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Asound.conf file unchanged, so no further update is needed Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting MPD Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting Shairport Sync Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:36 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:37 volumio-streamer-pi sudo[2728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 17:40:37 volumio-streamer-pi sudo[2728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi sudo[2730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 17:40:37 volumio-streamer-pi sudo[2730]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi sudo[2728]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 12 17:40:37 volumio-streamer-pi sudo[2740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:37 volumio-streamer-pi sudo[2740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: MPD Permissions set Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi sudo[2749]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:37 volumio-streamer-pi sudo[2749]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi sudo[2742]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:37 volumio-streamer-pi sudo[2742]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:37 volumio-streamer-pi systemd[1]: mpd.service: Deactivated successfully. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: mpd.service: Consumed 7.547s CPU time. Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: Starting Shairport Sync Apr 12 17:40:37 volumio-streamer-pi sudo[2740]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi sudo[2759]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 17:40:37 volumio-streamer-pi sudo[2759]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi sudo[2749]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi systemd[1]: mpd.socket: Deactivated successfully. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 17:40:37 volumio-streamer-pi sudo[2762]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:37 volumio-streamer-pi sudo[2762]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 17:40:37 volumio-streamer-pi systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: shairport-sync.service: Consumed 1.948s CPU time. Apr 12 17:40:37 volumio-streamer-pi sudo[2742]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:37 volumio-streamer-pi sudo[2759]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: Shairport-Sync Started Apr 12 17:40:37 volumio-streamer-pi sudo[2765]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 17:40:37 volumio-streamer-pi sudo[2765]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 17:40:37 volumio-streamer-pi sudo[2765]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:37 volumio-streamer-pi qobuz-connect[2289]: 20260412 17:40:37.926 [2289.2289] INFO SampleApp: shat down connection on UNIX socket Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:37 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:37 volumio-streamer-pi systemd[1]: qobuz-connect.service: Deactivated successfully. Apr 12 17:40:37 volumio-streamer-pi systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 17:40:38 volumio-streamer-pi systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 17:40:38 volumio-streamer-pi sudo[2715]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:38 volumio-streamer-pi sudo[2762]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:38 volumio-streamer-pi volumio[1225]: info: Executing endpoint qc_getconfig Apr 12 17:40:38 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.135 [2784.2784] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 17:40:38 volumio-streamer-pi volumio[1225]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 17:40:38 volumio-streamer-pi volumio[1225]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.141 [2784.2784] INFO VolumeManager: [0x15330e8]: Setting new playback volume: 75 Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.141 [2784.2784] INFO VolumeManager: [0x15330e8]: Setting new mute state: 0 Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.141 [2784.2784] INFO AudioStreamManager: [0x1532e40]: Setting new audio download buffer size: 1048576 Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.141 [2784.2784] INFO QobuzConnect: [0x15339b0]: Client initialized! Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.141 [2784.2784] INFO SampleApp: Starting Avahi advertising, name: Volumio Streamer Pi, service name: _qobuz-connect._tcp Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.184 [2784.2784] INFO LocalConfigManager: [0x1532b68]: Starting Local Configuration server Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.184 [2784.2784] INFO SampleApp: Starting Local configuration server Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.185 [2784.2784] INFO SampleApp: Connected to UNIX socket client 0x151d818 Apr 12 17:40:38 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:38.285 [2784.2784] INFO SampleApp: Playback volume changed: 75 Apr 12 17:40:38 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Preparing to save Alsa Options, stopping services first Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPause Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pause Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Device 3 does not have any Mixer Control Available Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:39 volumio-streamer-pi sudo[2803]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:39 volumio-streamer-pi sudo[2803]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Disabling external Volume Control Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:39 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:39.245-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:39 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:39.246-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Preparing to generate the ALSA configuration file Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Reading ALSA contributions from plugins. Apr 12 17:40:39 volumio-streamer-pi sudo[2803]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi sudo[2809]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:39 volumio-streamer-pi sudo[2809]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi sudo[2817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:39 volumio-streamer-pi sudo[2817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Getting Alsa Cards List without I2S DAC Apr 12 17:40:39 volumio-streamer-pi sudo[2809]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi sudo[2817]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi sudo[2828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:39 volumio-streamer-pi sudo[2828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 17:40:39 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:39.519 [2784.2784] INFO SampleApp: Stopping Local configuration server Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Asound.conf file unchanged, so no further update is needed Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting MPD Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting Shairport Sync Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi sudo[2836]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 17:40:39 volumio-streamer-pi sudo[2836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi sudo[2836]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi sudo[2838]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 17:40:39 volumio-streamer-pi sudo[2838]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:39 volumio-streamer-pi sudo[2848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:39 volumio-streamer-pi sudo[2848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi systemd[1]: mpd.service: Deactivated successfully. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: mpd.service: Consumed 2.001s CPU time. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: mpd.socket: Deactivated successfully. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 17:40:39 volumio-streamer-pi sudo[2851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:39 volumio-streamer-pi sudo[2851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: MPD Permissions set Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi sudo[2859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:39 volumio-streamer-pi sudo[2859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 17:40:39 volumio-streamer-pi systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Starting Shairport Sync Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:39 volumio-streamer-pi sudo[2868]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 17:40:39 volumio-streamer-pi sudo[2868]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi sudo[2859]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:39 volumio-streamer-pi volumio[1225]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:39 volumio-streamer-pi sudo[2881]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 17:40:39 volumio-streamer-pi sudo[2881]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi sudo[2848]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi sudo[2875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:39 volumio-streamer-pi sudo[2875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:39 volumio-streamer-pi sudo[2851]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:39 volumio-streamer-pi sudo[2866]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 17:40:39 volumio-streamer-pi sudo[2866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 17:40:39 volumio-streamer-pi sudo[2866]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 12 17:40:40 volumio-streamer-pi sudo[2881]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:40 volumio-streamer-pi systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:40 volumio-streamer-pi systemd[1]: shairport-sync.service: Consumed 1.550s CPU time. Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:40 volumio-streamer-pi sudo[2868]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2784]: 20260412 17:40:40.203 [2784.2784] INFO SampleApp: shat down connection on UNIX socket Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Shairport-Sync Started Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:40 volumio-streamer-pi systemd[1]: qobuz-connect.service: Deactivated successfully. Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 17:40:40 volumio-streamer-pi systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:40 volumio-streamer-pi sudo[2875]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:40 volumio-streamer-pi sudo[2828]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Executing endpoint qc_getconfig Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.391 [2892.2892] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: QobuzConnect: QOBUZ Connect daemon connected Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.400 [2892.2892] INFO VolumeManager: [0x23af0e8]: Setting new playback volume: 75 Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.401 [2892.2892] INFO VolumeManager: [0x23af0e8]: Setting new mute state: 0 Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.401 [2892.2892] INFO AudioStreamManager: [0x23aee40]: Setting new audio download buffer size: 1048576 Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.401 [2892.2892] INFO QobuzConnect: [0x23af9b0]: Client initialized! Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.401 [2892.2892] INFO SampleApp: Starting Avahi advertising, name: Volumio Streamer Pi, service name: _qobuz-connect._tcp Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.436 [2892.2892] INFO LocalConfigManager: [0x23aeb68]: Starting Local Configuration server Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.437 [2892.2892] INFO SampleApp: Starting Local configuration server Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.438 [2892.2892] INFO SampleApp: Connected to UNIX socket client 0x2399818 Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Executing endpoint tc_getconfig Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 12 17:40:40 volumio-streamer-pi vtcs[2889]: STARTING TidalConnect services, version: 1.6.1 Apr 12 17:40:40 volumio-streamer-pi vtcs[2889]: STARTED TidalConnect services. Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Executing endpoint tc_connect Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: Connecting to TidalConnect Apr 12 17:40:40 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:40.545 [2892.2892] INFO SampleApp: Playback volume changed: 75 Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::servicePushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CorePlayQueue::getTrack 0 Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.604-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.604-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.609-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.609-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:40 volumio-streamer-pi sudo[2932]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 17:40:40 volumio-streamer-pi sudo[2932]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::getcurrentVolume Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioRetrievevolume Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:40 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.707-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:40 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:40.708-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:40 volumio-streamer-pi sudo[2932]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Preparing to save Alsa Options, stopping services first Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPause Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pause Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-digi-pro","label":"HiFiBerry Digi+ Pro"}} Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Device 3 does not have any Mixer Control Available Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:42 volumio-streamer-pi vtcs[2889]: [2026-04-12 17:40:42.224] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 12 17:40:42 volumio-streamer-pi vtcs[2889]: [2026-04-12 17:40:42.225] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Updating Volume Controller Parameters: Device: 3 Name: HiFiBerry Digi+ Pro Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Disabling external Volume Control Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 17:40:42 volumio-streamer-pi sudo[2950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:42 volumio-streamer-pi sudo[2950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreStateMachine::pushState Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioPushState Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output update for this device Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: MRS: Pushing multiroomSync output Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 12 17:40:42 volumio-streamer-pi systemd[1]: vtcs.service: Killing process 2922 (vtcs) with signal SIGKILL. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: vtcs.service: Deactivated successfully. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 12 17:40:42 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:42.334-07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" state=STATUS_PAUSED positionMs=32944 volume=100 Apr 12 17:40:42 volumio-streamer-pi volumio5-onboarding[1706]: time=2026-04-12T17:40:42.335-07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.4.69:37980 @ 0x1c4e690" id=tidal://song/35986155 title="Whole Lotta Rosie" Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Preparing to generate the ALSA configuration file Apr 12 17:40:42 volumio-streamer-pi sudo[2950]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:42 volumio-streamer-pi sudo[2956]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:42 volumio-streamer-pi sudo[2956]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Reading ALSA contributions from plugins. Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:42 volumio-streamer-pi sudo[2963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:42 volumio-streamer-pi sudo[2956]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:42 volumio-streamer-pi sudo[2963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Getting Alsa Cards List without I2S DAC Apr 12 17:40:42 volumio-streamer-pi sudo[2963]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi sudo[2972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:42 volumio-streamer-pi sudo[2972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:42 volumio-streamer-pi qobuz-connect[2892]: 20260412 17:40:42.644 [2892.2892] INFO SampleApp: Stopping Local configuration server Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Asound.conf file unchanged, so no further update is needed Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting MPD Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Output device has changed, restarting Shairport Sync Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi sudo[2990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 12 17:40:42 volumio-streamer-pi sudo[2990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi sudo[2990]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi sudo[2994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 12 17:40:42 volumio-streamer-pi sudo[2994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: QobuzConnect: setDeactiveState invoked Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::volumioGetState Apr 12 17:40:42 volumio-streamer-pi sudo[3002]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:42 volumio-streamer-pi sudo[3002]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi systemd[1]: mpd.service: Deactivated successfully. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: mpd.service: Consumed 2.881s CPU time. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: mpd.socket: Deactivated successfully. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 12 17:40:42 volumio-streamer-pi sudo[3005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 12 17:40:42 volumio-streamer-pi sudo[3005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: MPD Permissions set Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:42 volumio-streamer-pi sudo[3013]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Apr 12 17:40:42 volumio-streamer-pi sudo[3013]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 12 17:40:42 volumio-streamer-pi systemd[1]: Starting mpd.service - Music Player Daemon... Apr 12 17:40:42 volumio-streamer-pi volumio[1225]: info: Starting Shairport Sync Apr 12 17:40:42 volumio-streamer-pi sudo[3022]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 17:40:42 volumio-streamer-pi sudo[3022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:43 volumio-streamer-pi sudo[3025]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 12 17:40:43 volumio-streamer-pi sudo[3025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: info: TidalConnect service stoped! Apr 12 17:40:43 volumio-streamer-pi sudo[3013]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 12 17:40:43 volumio-streamer-pi sudo[3002]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi sudo[3005]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi sudo[3035]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 17:40:43 volumio-streamer-pi sudo[3035]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:43 volumio-streamer-pi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 12 17:40:43 volumio-streamer-pi sudo[3037]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 12 17:40:43 volumio-streamer-pi sudo[3037]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: Error: Command failed: /usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3 Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: Job for vtcs.service canceled. Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at ChildProcess.exithandler (node:child_process:421:12) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at ChildProcess.emit (node:events:514:28) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at maybeClose (node:internal/child_process:1105:16) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at Socket. (node:internal/child_process:457:11) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at Socket.emit (node:events:514:28) Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: at Pipe. (node:net:337:12) { Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: code: 1, Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: killed: false, Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: signal: null, Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: cmd: '/usr/bin/sudo /bin/systemctl stop vtcs.service && sleep 3', Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: stdout: '', Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: stderr: 'Job for vtcs.service canceled.\n' Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: } Apr 12 17:40:43 volumio-streamer-pi volumio[1225]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 17:40:43 volumio-streamer-pi sudo[3021]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 12 17:40:43 volumio-streamer-pi sudo[3021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 12 17:40:43 volumio-streamer-pi sudo[3021]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 12 17:40:43 volumio-streamer-pi sudo[3022]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi systemd[1]: shairport-sync.service: Deactivated successfully. Apr 12 17:40:43 volumio-streamer-pi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:43 volumio-streamer-pi systemd[1]: shairport-sync.service: Consumed 1.577s CPU time. Apr 12 17:40:43 volumio-streamer-pi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 12 17:40:43 volumio-streamer-pi sudo[3025]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:43 volumio-streamer-pi sudo[3037]: pam_unix(sudo:session): session closed for user root Apr 12 17:40:44 volumio-streamer-pi sudo[3089]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 17:39' Apr 12 17:40:44 volumio-streamer-pi sudo[3089]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"