-- Logs begin at Mon 2025-09-08 11:18:26 EEST, end at Mon 2025-09-08 11:26:18 EEST. -- Sep 08 11:25:00 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:00 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:00 rivoplus volumio[3190]: 0 bytes copied, 0.000179042 s, 0.0 kB/s Sep 08 11:25:00 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6263, instance 1, run: true Sep 08 11:25:01 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:01 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:01 rivoplus volumio[3190]: 0 bytes copied, 0.000191375 s, 0.0 kB/s Sep 08 11:25:01 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6268, instance 1, run: true Sep 08 11:25:02 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:02 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:02 rivoplus volumio[3190]: 0 bytes copied, 0.000184625 s, 0.0 kB/s Sep 08 11:25:02 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6273, instance 1, run: true Sep 08 11:25:03 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:03 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:03 rivoplus volumio[3190]: 0 bytes copied, 0.000183125 s, 0.0 kB/s Sep 08 11:25:03 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6278, instance 1, run: true Sep 08 11:25:04 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:04 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:04 rivoplus volumio[3190]: 0 bytes copied, 0.000201166 s, 0.0 kB/s Sep 08 11:25:04 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6283, instance 1, run: true Sep 08 11:25:05 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:05 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:05 rivoplus volumio[3190]: 0 bytes copied, 0.000192791 s, 0.0 kB/s Sep 08 11:25:06 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6304, instance 1, run: true Sep 08 11:25:06 rivoplus volumio[3190]: Searching plugin music_service/tidal Sep 08 11:25:06 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 08 11:25:07 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:07 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:07 rivoplus volumio[3190]: 0 bytes copied, 0.00018725 s, 0.0 kB/s Sep 08 11:25:07 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6310, instance 1, run: true Sep 08 11:25:07 rivoplus volumio[3190]: info: searchTIDALUri took 638 milliseconds Sep 08 11:25:07 rivoplus volumio[3190]: info: search took 640 milliseconds Sep 08 11:25:07 rivoplus volumio[3190]: info: All search sources collected, pushing search results Sep 08 11:25:08 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:08 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:08 rivoplus volumio[3190]: 0 bytes copied, 0.000195417 s, 0.0 kB/s Sep 08 11:25:08 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6315, instance 1, run: true Sep 08 11:25:09 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:09 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:09 rivoplus volumio[3190]: 0 bytes copied, 0.000195084 s, 0.0 kB/s Sep 08 11:25:09 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6321, instance 1, run: true Sep 08 11:25:09 rivoplus volumio[3190]: Searching plugin music_service/tidal Sep 08 11:25:09 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , search Sep 08 11:25:10 rivoplus volumio[3190]: info: searchTIDALUri took 433 milliseconds Sep 08 11:25:10 rivoplus volumio[3190]: info: search took 434 milliseconds Sep 08 11:25:10 rivoplus volumio[3190]: info: All search sources collected, pushing search results Sep 08 11:25:10 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:10 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:10 rivoplus volumio[3190]: 0 bytes copied, 0.000195708 s, 0.0 kB/s Sep 08 11:25:10 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6326, instance 1, run: true Sep 08 11:25:11 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:11 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:11 rivoplus volumio[3190]: 0 bytes copied, 0.000185417 s, 0.0 kB/s Sep 08 11:25:11 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6331, instance 1, run: true Sep 08 11:25:12 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:12 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:12 rivoplus volumio[3190]: 0 bytes copied, 0.000170583 s, 0.0 kB/s Sep 08 11:25:12 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6336, instance 1, run: true Sep 08 11:25:13 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:13 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:13 rivoplus volumio[3190]: 0 bytes copied, 0.000175667 s, 0.0 kB/s Sep 08 11:25:13 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6341, instance 1, run: true Sep 08 11:25:14 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 11:25:14 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 11:25:14 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:14 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:14 rivoplus volumio[3190]: 0 bytes copied, 0.0001935 s, 0.0 kB/s Sep 08 11:25:14 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6347, instance 1, run: true Sep 08 11:25:15 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:15 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:15 rivoplus volumio[3190]: 0 bytes copied, 0.00019475 s, 0.0 kB/s Sep 08 11:25:15 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6352, instance 1, run: true Sep 08 11:25:15 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Sep 08 11:25:16 rivoplus volumio[3190]: info: browseTIDALUri took 1772 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: browseTIDALUri took 348 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: browseTIDALUri took 1529 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431170 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431167 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431168 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431171 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431173 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431174 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431175 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431176 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431179 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431177 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431180 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431181 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257333 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281305 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015288 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281293 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/87007959 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015302 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365464 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365466 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365465 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246260 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/249918583 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659575 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281296 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659470 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242533 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237904360 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015291 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257332 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246261 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257334 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281297 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242536 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015289 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659577 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246263 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659587 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281294 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281299 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237905050 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52095209 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431170 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431167 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431168 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431171 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431173 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431174 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431175 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431176 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431179 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431177 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431180 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431181 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257333 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281305 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015288 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281293 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/87007959 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015302 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365464 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365466 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365465 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246260 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/249918583 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659575 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281296 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659470 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242533 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237904360 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015291 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257332 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246261 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257334 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281297 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242536 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015289 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659577 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246263 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659587 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281294 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281299 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237905050 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52095209 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431170 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431167 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431168 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431171 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431173 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431174 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431175 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431176 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431179 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431177 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431180 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/413431181 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257333 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281305 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015288 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281293 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/87007959 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015302 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365464 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365466 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365465 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365467 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/408365468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246260 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/249918583 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659575 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659468 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281296 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659470 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242533 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237904360 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015291 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257332 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246261 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/117257334 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281297 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/54242536 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659469 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015289 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659577 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/207246263 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/221659587 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281294 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52015292 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/300281299 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/237905050 Sep 08 11:25:16 rivoplus volumio[3190]: info: Preloading song: tidal://song/52095209 Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431170 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431167 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431168 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431171 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431173 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431174 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Executing endpoint metavolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: Executing endpoint metavolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: Executing endpoint metavolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431175 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 325 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 289 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431176 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 298 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431179 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431177 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 326 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431180 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:16 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:16 rivoplus volumio[3190]: 0 bytes copied, 0.000186125 s, 0.0 kB/s Sep 08 11:25:16 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6374, instance 1, run: true Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 405 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/413431181 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/117257333 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 380 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 331 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281292 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281305 in service tidal Sep 08 11:25:16 rivoplus volumio[3190]: info: explodeTIDALUri took 310 milliseconds Sep 08 11:25:16 rivoplus volumio[3190]: info: Exploding uri tidal://song/52015288 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281293 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 363 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 323 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 497 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/87007959 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659467 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 283 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 315 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 295 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/52015302 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365464 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 308 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365466 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 285 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 276 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365465 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365469 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 313 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 273 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365467 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 283 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/408365468 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 265 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/207246260 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 273 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/249918583 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 289 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659575 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659468 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 301 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281296 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 254 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 298 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659470 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 253 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/54242533 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 271 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:17 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:17 rivoplus volumio[3190]: 0 bytes copied, 0.000192916 s, 0.0 kB/s Sep 08 11:25:17 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6382, instance 1, run: true Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 308 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/237904360 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/52015291 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 270 milliseconds Sep 08 11:25:17 rivoplus volumio[3190]: info: Exploding uri tidal://song/117257332 in service tidal Sep 08 11:25:17 rivoplus volumio[3190]: info: explodeTIDALUri took 271 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/207246261 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/117257334 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281297 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 334 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/54242536 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 285 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659469 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 295 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 260 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/52015289 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 517 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659577 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 261 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/207246263 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 375 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/221659587 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 316 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 289 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281294 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 297 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/52015292 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: error: Failed request for metavolumio API Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 274 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/300281299 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 320 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/237905050 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 273 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: Exploding uri tidal://song/52095209 in service tidal Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 326 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 304 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 293 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 282 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 275 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: info: explodeTIDALUri took 252 milliseconds Sep 08 11:25:18 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:18 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:18 rivoplus volumio[3190]: 0 bytes copied, 0.000185583 s, 0.0 kB/s Sep 08 11:25:19 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6387, instance 1, run: true Sep 08 11:25:20 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:20 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:20 rivoplus volumio[3190]: 0 bytes copied, 0.00018625 s, 0.0 kB/s Sep 08 11:25:20 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6392, instance 1, run: true Sep 08 11:25:21 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:21 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:21 rivoplus volumio[3190]: 0 bytes copied, 0.000195292 s, 0.0 kB/s Sep 08 11:25:21 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6397, instance 1, run: true Sep 08 11:25:22 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:22 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:22 rivoplus volumio[3190]: 0 bytes copied, 0.000189416 s, 0.0 kB/s Sep 08 11:25:22 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6402, instance 1, run: true Sep 08 11:25:23 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:23 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:23 rivoplus volumio[3190]: 0 bytes copied, 0.000183 s, 0.0 kB/s Sep 08 11:25:23 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6407, instance 1, run: true Sep 08 11:25:23 rivoplus volumio[3190]: error: Failed request for metavolumio API Sep 08 11:25:24 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:24 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:24 rivoplus volumio[3190]: 0 bytes copied, 0.000167208 s, 0.0 kB/s Sep 08 11:25:24 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6412, instance 1, run: true Sep 08 11:25:25 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:25 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:25 rivoplus volumio[3190]: 0 bytes copied, 0.000198709 s, 0.0 kB/s Sep 08 11:25:25 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6417, instance 1, run: true Sep 08 11:25:26 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:26 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:26 rivoplus volumio[3190]: 0 bytes copied, 0.000197875 s, 0.0 kB/s Sep 08 11:25:26 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6439, instance 1, run: true Sep 08 11:25:26 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreStateMachine::ClearQueue Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreStateMachine::stop Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:25:26 rivoplus volumio[3190]: info: CorePlayQueue::clearPlayQueue Sep 08 11:25:26 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreStateMachine::addQueueItems Sep 08 11:25:26 rivoplus volumio[3190]: info: CorePlayQueue::addQueueItems Sep 08 11:25:26 rivoplus volumio[3190]: info: Preload queue cleared Sep 08 11:25:26 rivoplus volumio[3190]: info: Adding Item to queue: tidal://artist/3659746/207246259 Sep 08 11:25:26 rivoplus volumio[3190]: info: Exploding uri tidal://artist/3659746/207246259 in service tidal Sep 08 11:25:26 rivoplus volumio[3190]: info: explodeTIDALUri took 409 milliseconds Sep 08 11:25:26 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushQueue Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::saveQueue Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::updateTrackBlock Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrackBlock Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPlay Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::play index 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::stop Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::play index undefined Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::startPlaybackTimer Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: [1757319927011] ControllerTidal::clearAddPlayTrack Sep 08 11:25:27 rivoplus volumio[3190]: info: Getting stream with soundQuality HI_RES Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand stop Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand stop took 16 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand clear Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand clear took 4 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES" Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: error: updateQueue error: null Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 17ms Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES" took 18 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 17ms Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 14ms Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 13ms Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand play Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces system playlist update Sep 08 11:25:27 rivoplus volumio[3190]: info: Ignoring MPD Status Update Sep 08 11:25:27 rivoplus volumio[3190]: error: Upnp client error: Error: This socket has been ended by the other party Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 23ms Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand play took 13 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 12ms Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 11ms Sep 08 11:25:27 rivoplus volumio[3190]: info: ------------------------------ 9ms Sep 08 11:25:27 rivoplus volumio[3190]: STREAMING PROXY: Handling url /?data=tidal://song/207246260&soundQuality=HI_RES Sep 08 11:25:27 rivoplus volumio[3190]: info: Executing endpoint getStreamUrltidal Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Sep 08 11:25:27 rivoplus volumio[3190]: info: getStreamUrl took 310 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0ZjNlYTQ4OTZlZWQ3MDFhZmYwZWNhODcxMDA3Y2Y5ZF82Mi5tcDQ/0.flac?token=1757323527~MDlhM2FhNmE1NTY0NTAyZjBlNjdmODU2NjEwNWNiYWJlMTkxNjU4Mw== Sep 08 11:25:27 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:27 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:27 rivoplus volumio[3190]: 0 bytes copied, 0.000187917 s, 0.0 kB/s Sep 08 11:25:27 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6451, instance 1, run: true Sep 08 11:25:27 rivoplus volumio[3190]: STREAMING PROXY: Response: 200, length: 169642781 Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate from file: 96000 Sep 08 11:25:27 rivoplus volumio[3190]: info: camilladsp service terminated, instance 1 Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 11:25:27 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6456, instance 1, run: true Sep 08 11:25:27 rivoplus volumio[3190]: info: camilladsp service started and running in background, instance 1 Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate, raw: 96000,S32_LE,2,32 Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - ---- read samplerate from file: 96000 Sep 08 11:25:27 rivoplus volumio[3190]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 102 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 101 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 98 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 92 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: Sep 08 11:25:27 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 21 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 16 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 14 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 11 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 10 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: info: sendMpdCommand status took 8 milliseconds Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:27 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:27 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus stop Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:27 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:27 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:27 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:27 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:27 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:27 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:27 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:27 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:27 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:27 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 207ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 238ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 238ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 236ms Sep 08 11:25:28 rivoplus volumio[3190]: error: FusionDsp - WebSocket error: [object Object] Sep 08 11:25:28 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 124 milliseconds Sep 08 11:25:28 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 119 milliseconds Sep 08 11:25:28 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 119 milliseconds Sep 08 11:25:28 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 119 milliseconds Sep 08 11:25:28 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:28 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:28 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:28 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:28 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:28 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2610 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:28 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:28 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:28 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2602 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:28 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:28 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:28 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2602 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:28 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:28 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus ntpd[3073]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:28 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2602 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:28 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService play Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus play Sep 08 11:25:28 rivoplus volumio[3190]: info: Received an update from plugin. extracting info from payload Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:28 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:28 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 283ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 270ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 269ms Sep 08 11:25:28 rivoplus volumio[3190]: info: ------------------------------ 268ms Sep 08 11:25:28 rivoplus volumio[3190]: info: Signalling Playback active due to playback status change Sep 08 11:25:28 rivoplus volumio[3190]: info: MCU Signalled Playback Active Sep 08 11:25:28 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:28 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:28 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:28 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000514665 s, 127 MB/s Sep 08 11:25:28 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6461, instance 1, run: true Sep 08 11:25:28 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:28 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:28 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:28 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000569291 s, 115 MB/s Sep 08 11:25:29 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6466, instance 1, run: true Sep 08 11:25:29 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:29 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:29 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:29 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000582748 s, 112 MB/s Sep 08 11:25:29 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6476, instance 1, run: true Sep 08 11:25:30 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:30 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:30 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:30 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000529029 s, 124 MB/s Sep 08 11:25:30 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6481, instance 1, run: true Sep 08 11:25:30 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:30 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:30 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:30 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000584486 s, 112 MB/s Sep 08 11:25:30 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6490, instance 1, run: true Sep 08 11:25:31 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:31 rivoplus volumio[3190]: 1+1 records in Sep 08 11:25:31 rivoplus volumio[3190]: 1+1 records out Sep 08 11:25:31 rivoplus volumio[3190]: 64512 bytes (65 kB, 63 KiB) copied, 0.000529946 s, 122 MB/s Sep 08 11:25:31 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6533, instance 1, run: true Sep 08 11:25:31 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:31 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:31 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:31 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000510197 s, 128 MB/s Sep 08 11:25:31 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6540, instance 1, run: true Sep 08 11:25:32 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:32 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:32 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:32 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000485781 s, 135 MB/s Sep 08 11:25:32 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6545, instance 1, run: true Sep 08 11:25:33 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:33 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:33 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:33 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000500572 s, 131 MB/s Sep 08 11:25:33 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6552, instance 1, run: true Sep 08 11:25:33 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:33 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:33 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:33 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.00049124 s, 133 MB/s Sep 08 11:25:33 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6557, instance 1, run: true Sep 08 11:25:34 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:34 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:34 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:34 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000508948 s, 129 MB/s Sep 08 11:25:34 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6564, instance 1, run: true Sep 08 11:25:34 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:34 rivoplus volumio[3190]: 1+1 records in Sep 08 11:25:34 rivoplus volumio[3190]: 1+1 records out Sep 08 11:25:34 rivoplus volumio[3190]: 64512 bytes (65 kB, 63 KiB) copied, 0.000502614 s, 128 MB/s Sep 08 11:25:34 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6569, instance 1, run: true Sep 08 11:25:35 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:35 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:35 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:35 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000485864 s, 135 MB/s Sep 08 11:25:35 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6577, instance 1, run: true Sep 08 11:25:35 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:35 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:35 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:35 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000487365 s, 134 MB/s Sep 08 11:25:35 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6582, instance 1, run: true Sep 08 11:25:36 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:36 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:36 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:36 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000494157 s, 133 MB/s Sep 08 11:25:36 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6606, instance 1, run: true Sep 08 11:25:36 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:36 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:36 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:36 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.00048799 s, 134 MB/s Sep 08 11:25:36 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6611, instance 1, run: true Sep 08 11:25:37 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:37 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:37 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:37 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000505616 s, 130 MB/s Sep 08 11:25:37 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6618, instance 1, run: true Sep 08 11:25:37 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:37 rivoplus volumio[3190]: 1+1 records in Sep 08 11:25:37 rivoplus volumio[3190]: 1+1 records out Sep 08 11:25:37 rivoplus volumio[3190]: 64512 bytes (65 kB, 63 KiB) copied, 0.000574364 s, 112 MB/s Sep 08 11:25:37 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6625, instance 1, run: true Sep 08 11:25:38 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:38 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:38 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:38 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000500949 s, 131 MB/s Sep 08 11:25:38 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6633, instance 1, run: true Sep 08 11:25:38 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:38 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:38 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:38 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000500199 s, 131 MB/s Sep 08 11:25:38 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6638, instance 1, run: true Sep 08 11:25:39 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:39 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:39 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:39 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000503365 s, 130 MB/s Sep 08 11:25:39 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6645, instance 1, run: true Sep 08 11:25:39 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:39 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:39 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:39 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000504741 s, 130 MB/s Sep 08 11:25:39 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6650, instance 1, run: true Sep 08 11:25:40 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:40 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:40 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:40 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000661863 s, 99.0 MB/s Sep 08 11:25:40 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6657, instance 1, run: true Sep 08 11:25:40 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:40 rivoplus volumio[3190]: 1+1 records in Sep 08 11:25:40 rivoplus volumio[3190]: 1+1 records out Sep 08 11:25:40 rivoplus volumio[3190]: 64512 bytes (65 kB, 63 KiB) copied, 0.000502199 s, 128 MB/s Sep 08 11:25:40 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6662, instance 1, run: true Sep 08 11:25:41 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:41 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:41 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:41 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000797236 s, 82.2 MB/s Sep 08 11:25:41 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6669, instance 1, run: true Sep 08 11:25:41 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:41 rivoplus volumio[3190]: 2+30 records in Sep 08 11:25:41 rivoplus volumio[3190]: 2+30 records out Sep 08 11:25:41 rivoplus volumio[3190]: 192512 bytes (193 kB, 188 KiB) copied, 0.000719529 s, 268 MB/s Sep 08 11:25:41 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6674, instance 1, run: true Sep 08 11:25:42 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:42 rivoplus volumio[3190]: 2+0 records in Sep 08 11:25:42 rivoplus volumio[3190]: 2+0 records out Sep 08 11:25:42 rivoplus volumio[3190]: 65536 bytes (66 kB, 64 KiB) copied, 0.000494909 s, 132 MB/s Sep 08 11:25:42 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6681, instance 1, run: true Sep 08 11:25:42 rivoplus volumio[3190]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Sep 08 11:25:42 rivoplus volumio[3190]: 1+1 records in Sep 08 11:25:42 rivoplus volumio[3190]: 1+1 records out Sep 08 11:25:42 rivoplus volumio[3190]: 64512 bytes (65 kB, 63 KiB) copied, 0.000568865 s, 113 MB/s Sep 08 11:25:43 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6686, instance 1, run: true Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::servicePause Sep 08 11:25:43 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::servicePause Sep 08 11:25:43 rivoplus volumio[3190]: info: [1757319943400] ControllerTidal::pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::pause Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand pause Sep 08 11:25:43 rivoplus volumio[3190]: info: Sep 08 11:25:43 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand pause took 5 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:43 rivoplus volumio[3190]: info: Sep 08 11:25:43 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:43 rivoplus volumio[3190]: info: Sep 08 11:25:43 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:43 rivoplus volumio[3190]: info: Sep 08 11:25:43 rivoplus volumio[3190]: ---------------------------- MPD announces state update: player Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::getState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand status Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand status took 8 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand status took 7 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand status took 4 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand status took 3 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseState Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 9 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:43 rivoplus volumio[3190]: verbose: ControllerMpd::parseTrackInfo Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:43 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4242,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3290 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:43 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:43 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4242,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3290 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:43 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:43 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4242,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3290 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:43 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:25:43 rivoplus volumio[3190]: info: ControllerMpd::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:43 rivoplus volumio[3190]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4242,"duration":419,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3290 Kbps","isStreaming":false,"title":"207246260&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/207246260&soundQuality=HI_RES","trackType":"tidal"} Sep 08 11:25:43 rivoplus volumio[3190]: verbose: CURRENT POSITION 0 Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState stateService pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::syncState currentStatus pause Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:43 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:43 rivoplus volumio[3190]: info: CoreStateMachine::stPlaybackTimer Sep 08 11:25:43 rivoplus volumio[3190]: info: ------------------------------ 77ms Sep 08 11:25:43 rivoplus volumio[3190]: info: ------------------------------ 75ms Sep 08 11:25:43 rivoplus volumio[3190]: info: ------------------------------ 74ms Sep 08 11:25:43 rivoplus volumio[3190]: info: ------------------------------ 72ms Sep 08 11:25:43 rivoplus volumio[3190]: info: MCU Signalled Playback Inactive Sep 08 11:25:43 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:43 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:43 rivoplus volumio[3190]: 0 bytes copied, 0.000188497 s, 0.0 kB/s Sep 08 11:25:43 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6695, instance 1, run: true Sep 08 11:25:44 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:44 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:44 rivoplus volumio[3190]: 0 bytes copied, 0.000188497 s, 0.0 kB/s Sep 08 11:25:44 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6700, instance 1, run: true Sep 08 11:25:44 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:44 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:44 rivoplus volumio[3190]: 0 bytes copied, 0.000187789 s, 0.0 kB/s Sep 08 11:25:44 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6705, instance 1, run: true Sep 08 11:25:45 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:45 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:45 rivoplus volumio[3190]: 0 bytes copied, 0.000196747 s, 0.0 kB/s Sep 08 11:25:45 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6710, instance 1, run: true Sep 08 11:25:45 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:45 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:45 rivoplus volumio[3190]: 0 bytes copied, 0.000198788 s, 0.0 kB/s Sep 08 11:25:45 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6715, instance 1, run: true Sep 08 11:25:46 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:46 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:46 rivoplus volumio[3190]: 0 bytes copied, 0.000182706 s, 0.0 kB/s Sep 08 11:25:46 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6720, instance 1, run: true Sep 08 11:25:47 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:47 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:47 rivoplus volumio[3190]: 0 bytes copied, 0.000189122 s, 0.0 kB/s Sep 08 11:25:47 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6742, instance 1, run: true Sep 08 11:25:47 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:47 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:47 rivoplus volumio[3190]: 0 bytes copied, 0.000180081 s, 0.0 kB/s Sep 08 11:25:47 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6747, instance 1, run: true Sep 08 11:25:48 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:48 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:48 rivoplus volumio[3190]: 0 bytes copied, 0.000183581 s, 0.0 kB/s Sep 08 11:25:48 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6752, instance 1, run: true Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 11:25:48 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:48 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:48 rivoplus volumio[3190]: 0 bytes copied, 0.000177372 s, 0.0 kB/s Sep 08 11:25:48 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6757, instance 1, run: true Sep 08 11:25:48 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 11:25:48 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:48 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:49 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:49 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:49 rivoplus volumio[3190]: 0 bytes copied, 0.000193998 s, 0.0 kB/s Sep 08 11:25:49 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6766, instance 1, run: true Sep 08 11:25:49 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:49 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:49 rivoplus volumio[3190]: 0 bytes copied, 0.000335829 s, 0.0 kB/s Sep 08 11:25:49 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6771, instance 1, run: true Sep 08 11:25:50 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:50 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:50 rivoplus volumio[3190]: 0 bytes copied, 0.000186497 s, 0.0 kB/s Sep 08 11:25:50 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6776, instance 1, run: true Sep 08 11:25:50 rivoplus volumio[3190]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 08 11:25:50 rivoplus volumio[3190]: info: Preparing to save Alsa Options, stopping services first Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPause Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreStateMachine::pause Sep 08 11:25:50 rivoplus volumio[3190]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"S/PDIF + AES/EBU + I2S"}} Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 11:25:50 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:50 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 11:25:50 rivoplus volumio[3190]: info: Found match in Cards Database for ignoring default Mixer Sep 08 11:25:50 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:50 rivoplus volumio[3190]: info: Setting mixer PCM for card S/PDIF + AES/EBU + I2S Sep 08 11:25:50 rivoplus volumio[3190]: info: QobuzConnect: setDeactiveState invoked Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:50 rivoplus volumio[3190]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Sep 08 11:25:50 rivoplus vtcs[3743]: [2025-09-08 11:25:50.593] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Sep 08 11:25:50 rivoplus vtcs[3743]: [2025-09-08 11:25:50.595] [tisoc] [error] [SpkconServer.cpp:380] recv error. client fd=7 errorno=104 error=Connection reset by peer Sep 08 11:25:50 rivoplus vtcs[3743]: [2025-09-08 11:25:50.595] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Sep 08 11:25:50 rivoplus volumio[3190]: info: Ignoring Mixers Options Sep 08 11:25:50 rivoplus volumio[3190]: info: Device 5 does not have any Mixer Control Available Sep 08 11:25:50 rivoplus volumio[3190]: info: QobuzConnect: setDeactiveState invoked Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:50 rivoplus volumio[3190]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes Sep 08 11:25:50 rivoplus sudo[6786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:50 rivoplus sudo[6786]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:50 rivoplus systemd[1]: Stopping Volumio Tidal Connect Service... Sep 08 11:25:50 rivoplus systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Sep 08 11:25:50 rivoplus systemd[1]: vtcs.service: Succeeded. Sep 08 11:25:50 rivoplus systemd[1]: Stopped Volumio Tidal Connect Service. Sep 08 11:25:50 rivoplus sudo[6786]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:50 rivoplus sudo[6789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:50 rivoplus sudo[6789]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:50 rivoplus sudo[6789]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:50 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 08 11:25:50 rivoplus volumio[3190]: info: Updating Volume Controller Parameters: Device: 5 Name: S/PDIF + AES/EBU + I2S Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 08 11:25:50 rivoplus volumio[3190]: info: Disabling external Volume Control Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:50 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:50 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:50 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:50 rivoplus volumio[3190]: info: Preparing to generate the ALSA configuration file Sep 08 11:25:50 rivoplus sudo[6797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:50 rivoplus sudo[6797]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:50 rivoplus sudo[6800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:50 rivoplus sudo[6800]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:50 rivoplus sudo[6797]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:50 rivoplus sudo[6800]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:50 rivoplus sudo[6808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 08 11:25:50 rivoplus sudo[6808]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus sudo[6808]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus sudo[6813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 08 11:25:51 rivoplus sudo[6813]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus sudo[6816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 08 11:25:51 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 08 11:25:51 rivoplus sudo[6816]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus qobuz-connect[3670]: 20250908 11:25:51.069 [3670.3670] INFO SampleApp: Stopping Local configuration server Sep 08 11:25:51 rivoplus qobuz-connect[3670]: 20250908 11:25:51.080 [3670.3670] INFO SampleApp: shat down connection on UNIX socket Sep 08 11:25:51 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:51 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:51 rivoplus volumio[3190]: 0 bytes copied, 0.000178081 s, 0.0 kB/s Sep 08 11:25:51 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Sep 08 11:25:51 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 08 11:25:51 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Sep 08 11:25:51 rivoplus sudo[6813]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6820, instance 1, run: true Sep 08 11:25:51 rivoplus sudo[6816]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:51 rivoplus sudo[6822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 08 11:25:51 rivoplus sudo[6822]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 08 11:25:51 rivoplus systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Sep 08 11:25:51 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Sep 08 11:25:51 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 08 11:25:51 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:51 rivoplus sudo[6822]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus volumio[3190]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 08 11:25:51 rivoplus volumio[3190]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 08 11:25:51 rivoplus volumio[3190]: info: Reading ALSA contributions from plugins. Sep 08 11:25:51 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:51 rivoplus volumio[3190]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 08 11:25:51 rivoplus volumio[3190]: info: QobuzConnect: setDeactiveState invoked Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: Not Reporting Auto name since its the default one Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: Not Reporting Auto name since its the default one Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:51 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:51 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:51 rivoplus volumio[3190]: 0 bytes copied, 0.000187872 s, 0.0 kB/s Sep 08 11:25:51 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6849, instance 1, run: true Sep 08 11:25:51 rivoplus volumio[3190]: info: Executing endpoint qc_getconfig Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 08 11:25:51 rivoplus volumio[3190]: info: Executing endpoint qc_getconfig Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.492 [6832.6832] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.498 [6832.6832] INFO VolumeManager: [0xab6237a0]: Setting new playback volume: 75 Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.499 [6832.6832] INFO VolumeManager: [0xab6237a0]: Setting new mute state: 0 Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.499 [6832.6832] INFO QobuzConnect: [0xab624170]: Client initialized! Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.499 [6832.6832] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.511 [6832.6832] INFO LocalConfigManager: [0xab623080]: Starting Local Configuration server Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.511 [6832.6832] INFO SampleApp: Starting Local configuration server Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.512 [6832.6832] INFO SampleApp: Connected to UNIX socket client 0xab618ed8 Sep 08 11:25:51 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 08 11:25:51 rivoplus qobuz-connect[6832]: 20250908 11:25:51.642 [6832.6832] INFO SampleApp: Playback volume changed: 75 Sep 08 11:25:51 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Sep 08 11:25:51 rivoplus volumio[3190]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 08 11:25:51 rivoplus volumio[3190]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 08 11:25:51 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 08 11:25:51 rivoplus sudo[6860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Sep 08 11:25:51 rivoplus sudo[6860]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus sudo[6860]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus sudo[6864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 08 11:25:51 rivoplus sudo[6864]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:51 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:51 rivoplus systemd[1]: Stopping RAAT DAEMON... Sep 08 11:25:51 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 11:25:51 rivoplus systemd[1]: raat-daemon.service: Succeeded. Sep 08 11:25:51 rivoplus systemd[1]: Stopped RAAT DAEMON. Sep 08 11:25:51 rivoplus systemd[1]: Started RAAT DAEMON. Sep 08 11:25:51 rivoplus sudo[6864]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:51 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:51 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:51 rivoplus sudo[6868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Sep 08 11:25:51 rivoplus sudo[6868]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus volumio[3190]: info: Raat Daemon started successfully Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus sudo[6868]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus volumio[3190]: info: Asound.conf file unchanged, so no further update is needed Sep 08 11:25:52 rivoplus volumio[3190]: info: Output device has changed, restarting MPD Sep 08 11:25:52 rivoplus sudo[6878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 08 11:25:52 rivoplus sudo[6878]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus systemd[1]: Stopping RAAT DAEMON... Sep 08 11:25:52 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 11:25:52 rivoplus systemd[1]: raat-daemon.service: Succeeded. Sep 08 11:25:52 rivoplus systemd[1]: Stopped RAAT DAEMON. Sep 08 11:25:52 rivoplus systemd[1]: Started RAAT DAEMON. Sep 08 11:25:52 rivoplus sudo[6878]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus sudo[6882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 08 11:25:52 rivoplus sudo[6882]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus volumio[3190]: info: Output device has changed, restarting Shairport Sync Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:25:52 rivoplus sudo[6882]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus sudo[6886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 08 11:25:52 rivoplus sudo[6886]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus systemd[1]: Stopping Music Player Daemon... Sep 08 11:25:52 rivoplus volumio[3190]: STREAMING PROXY: Client dropped request, destroying Sep 08 11:25:52 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:52 rivoplus volumio[3190]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 08 11:25:52 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:52 rivoplus volumio[3190]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: QobuzConnect: setDeactiveState invoked Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:52 rivoplus volumio[3190]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus sudo[6904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:52 rivoplus sudo[6904]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus sudo[6907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 08 11:25:52 rivoplus sudo[6907]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus sudo[6904]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus systemd[1]: mpd.service: Succeeded. Sep 08 11:25:52 rivoplus systemd[1]: Stopped Music Player Daemon. Sep 08 11:25:52 rivoplus systemd[1]: Starting Music Player Daemon... Sep 08 11:25:52 rivoplus sudo[6907]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus sudo[6916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 08 11:25:52 rivoplus sudo[6916]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:52 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:52 rivoplus volumio[3190]: 0 bytes copied, 0.000193456 s, 0.0 kB/s Sep 08 11:25:52 rivoplus sudo[6916]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus sudo[6920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 08 11:25:52 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6921, instance 1, run: true Sep 08 11:25:52 rivoplus sudo[6920]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:52 rivoplus qobuz-connect[6832]: 20250908 11:25:52.548 [6832.6832] INFO SampleApp: Stopping Local configuration server Sep 08 11:25:52 rivoplus systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:52 rivoplus qobuz-connect[6832]: 20250908 11:25:52.559 [6832.6832] INFO SampleApp: shat down connection on UNIX socket Sep 08 11:25:52 rivoplus systemd[1]: qobuz-connect.service: Succeeded. Sep 08 11:25:52 rivoplus systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 08 11:25:52 rivoplus sudo[6911]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 08 11:25:52 rivoplus sudo[6911]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:52 rivoplus sudo[6911]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:52 rivoplus systemd[1]: Started Volumio Qobuz Connect Service. Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:52 rivoplus sudo[6920]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:52 rivoplus volumio[3190]: info: MPD Permissions set Sep 08 11:25:52 rivoplus volumio[3190]: info: Raat Daemon started successfully Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:52 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:52 rivoplus volumio[3190]: info: Executing endpoint restartRAATSocket Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 08 11:25:52 rivoplus volumio[3190]: info: Executing endpoint restartRAATSocket Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 08 11:25:52 rivoplus volumio[3190]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 08 11:25:52 rivoplus volumio[3190]: info: QobuzConnect: setDeactiveState invoked Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:52 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:52 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:52 rivoplus volumio[3190]: 0 bytes copied, 0.000190498 s, 0.0 kB/s Sep 08 11:25:52 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6934, instance 1, run: true Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: Not Reporting Auto name since its the default one Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:52 rivoplus volumio[3190]: info: Executing endpoint qc_getconfig Sep 08 11:25:52 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.969 [6926.6926] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.976 [6926.6926] INFO VolumeManager: [0xac94c7a0]: Setting new playback volume: 75 Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.976 [6926.6926] INFO VolumeManager: [0xac94c7a0]: Setting new mute state: 0 Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.976 [6926.6926] INFO QobuzConnect: [0xac94d170]: Client initialized! Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.976 [6926.6926] INFO SampleApp: Starting Avahi advertising, name: Rivoplus, service name: _qobuz-connect._tcp Sep 08 11:25:52 rivoplus volumio[3190]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 08 11:25:52 rivoplus volumio[3190]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.995 [6926.6926] INFO LocalConfigManager: [0xac94c080]: Starting Local Configuration server Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.995 [6926.6926] INFO SampleApp: Starting Local configuration server Sep 08 11:25:52 rivoplus qobuz-connect[6926]: 20250908 11:25:52.996 [6926.6926] INFO SampleApp: Connected to UNIX socket client 0xac941ed8 Sep 08 11:25:52 rivoplus volumio[3190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 08 11:25:53 rivoplus volumio[3190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 08 11:25:53 rivoplus volumio[3190]: info: Starting Shairport Sync Sep 08 11:25:53 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Sep 08 11:25:53 rivoplus sudo[6949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 08 11:25:53 rivoplus sudo[6949]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:53 rivoplus systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 08 11:25:53 rivoplus systemd[1]: shairport-sync.service: Succeeded. Sep 08 11:25:53 rivoplus systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 08 11:25:53 rivoplus systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 08 11:25:53 rivoplus sudo[6949]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:53 rivoplus qobuz-connect[6926]: 20250908 11:25:53.119 [6926.6926] INFO SampleApp: Playback volume changed: 75 Sep 08 11:25:53 rivoplus volumio[3190]: xcb_connection_has_error() returned true Sep 08 11:25:53 rivoplus sudo[6956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Sep 08 11:25:53 rivoplus sudo[6956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:53 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:53 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:53 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:53 rivoplus volumio[3190]: info: Shairport-Sync Started Sep 08 11:25:53 rivoplus sudo[6956]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:53 rivoplus sudo[6961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Sep 08 11:25:53 rivoplus sudo[6961]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:53 rivoplus systemd[1]: Stopping RAAT DAEMON... Sep 08 11:25:53 rivoplus systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Sep 08 11:25:53 rivoplus systemd[1]: raat-daemon.service: Succeeded. Sep 08 11:25:53 rivoplus systemd[1]: Stopped RAAT DAEMON. Sep 08 11:25:53 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 08 11:25:53 rivoplus volumio[3190]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 08 11:25:53 rivoplus systemd[1]: Started RAAT DAEMON. Sep 08 11:25:53 rivoplus sudo[6961]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:53 rivoplus volumio[3190]: info: Raat Daemon started successfully Sep 08 11:25:53 rivoplus volumio[3190]: info: Executing endpoint restartRAATSocket Sep 08 11:25:53 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Sep 08 11:25:53 rivoplus volumio[3190]: info: CALLMETHOD: music_service inputs saveAdvancedAudioSettings [object Object] Sep 08 11:25:53 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , saveAdvancedAudioSettings Sep 08 11:25:53 rivoplus volumio[3190]: info: Setting HDMI I2S Mode to 13 (Gustard) Sep 08 11:25:53 rivoplus volumio[3190]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Sep 08 11:25:53 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:53 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:53 rivoplus volumio[3190]: 0 bytes copied, 0.000201331 s, 0.0 kB/s Sep 08 11:25:53 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6976, instance 1, run: true Sep 08 11:25:53 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:53 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:53 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:53 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:54 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:54 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:54 rivoplus volumio[3190]: 0 bytes copied, 0.00021029 s, 0.0 kB/s Sep 08 11:25:54 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 6991, instance 1, run: true Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:54 rivoplus sudo[7006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 08 11:25:54 rivoplus sudo[7006]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:54 rivoplus systemd[1]: Started Volumio Tidal Connect Service. Sep 08 11:25:54 rivoplus sudo[7009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 08 11:25:54 rivoplus sudo[7009]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:54 rivoplus sudo[7006]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:54 rivoplus sudo[7009]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:54 rivoplus mpd[6927]: Sep 08 11:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 08 11:25:54 rivoplus volumio[3190]: info: Executing endpoint tc_getconfig Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 08 11:25:54 rivoplus vtcs[7010]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 08 11:25:54 rivoplus vtcs[7010]: STARTED TidalConnect services. Sep 08 11:25:54 rivoplus volumio[3190]: info: Executing endpoint tc_connect Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 08 11:25:54 rivoplus volumio[3190]: info: Connecting to TidalConnect Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:54 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:54 rivoplus volumio[3190]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::servicePushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:54 rivoplus volumio[3190]: info: CorePlayQueue::getTrack 0 Sep 08 11:25:54 rivoplus volumio[3190]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Sep 08 11:25:54 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:54 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:54 rivoplus volumio[3190]: 0 bytes copied, 0.000215831 s, 0.0 kB/s Sep 08 11:25:54 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7034, instance 1, run: true Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreStateMachine::getcurrentVolume Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioRetrievevolume Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreStateMachine::pushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioPushState Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output update for this device Sep 08 11:25:54 rivoplus volumio[3190]: info: MRS: Pushing multiroomSync output Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:54 rivoplus volumio[3190]: info: RAAT: Requesting Headphone Status Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 08 11:25:54 rivoplus volumio[3190]: info: RAAT: Requesting Headphone Status Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 08 11:25:54 rivoplus volumio[3190]: info: MCU Signalled Headphone Mode Disabled Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 08 11:25:54 rivoplus volumio[3190]: info: Reporting Headphone State: false Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:54 rivoplus volumio[3190]: info: MCU Signalled Headphone Mode Disabled Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 08 11:25:54 rivoplus volumio[3190]: info: Reporting Headphone State: false Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:54 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:54 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:55 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:55 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:55 rivoplus volumio[3190]: 0 bytes copied, 0.000216622 s, 0.0 kB/s Sep 08 11:25:55 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7039, instance 1, run: true Sep 08 11:25:55 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:55 rivoplus volumio[3190]: info: TidalConnect service stoped! Sep 08 11:25:55 rivoplus volumio[3190]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:55 rivoplus volumio[3190]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 08 11:25:55 rivoplus sudo[7051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 08 11:25:55 rivoplus sudo[7051]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 08 11:25:55 rivoplus sudo[7051]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:55 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:55 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:55 rivoplus volumio[3190]: 0 bytes copied, 0.000224247 s, 0.0 kB/s Sep 08 11:25:55 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7056, instance 1, run: true Sep 08 11:25:56 rivoplus volumio[3190]: info: RAAT: Requesting Headphone Status Sep 08 11:25:56 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus Sep 08 11:25:56 rivoplus volumio[3190]: info: MCU Signalled Headphone Mode Disabled Sep 08 11:25:56 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Sep 08 11:25:56 rivoplus volumio[3190]: info: Reporting Headphone State: false Sep 08 11:25:56 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 08 11:25:56 rivoplus volumio[3190]: info: Updating RAAT Signal Path Sep 08 11:25:56 rivoplus systemd[1]: Started Music Player Daemon. Sep 08 11:25:56 rivoplus sudo[6886]: pam_unix(sudo:session): session closed for user root Sep 08 11:25:56 rivoplus volumio[3190]: error: updateQueue error: null Sep 08 11:25:56 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:56 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:56 rivoplus volumio[3190]: 0 bytes copied, 0.000181706 s, 0.0 kB/s Sep 08 11:25:56 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7080, instance 1, run: true Sep 08 11:25:57 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:57 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:57 rivoplus volumio[3190]: 0 bytes copied, 0.000191331 s, 0.0 kB/s Sep 08 11:25:57 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7085, instance 1, run: true Sep 08 11:25:57 rivoplus volumio[3190]: info: TidalConnect service started! Sep 08 11:25:57 rivoplus volumio[3190]: info: TidalConnect service started! Sep 08 11:25:57 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:57 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:57 rivoplus volumio[3190]: 0 bytes copied, 0.000167082 s, 0.0 kB/s Sep 08 11:25:57 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7090, instance 1, run: true Sep 08 11:25:58 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:58 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:58 rivoplus volumio[3190]: 0 bytes copied, 0.000186831 s, 0.0 kB/s Sep 08 11:25:58 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7098, instance 1, run: true Sep 08 11:25:58 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:58 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:58 rivoplus volumio[3190]: 0 bytes copied, 0.000182665 s, 0.0 kB/s Sep 08 11:25:58 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7103, instance 1, run: true Sep 08 11:25:58 rivoplus volumio[3190]: info: TidalConnect service started! Sep 08 11:25:59 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:59 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:59 rivoplus volumio[3190]: 0 bytes copied, 0.000182539 s, 0.0 kB/s Sep 08 11:25:59 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7108, instance 1, run: true Sep 08 11:25:59 rivoplus volumio[3190]: 0+0 records in Sep 08 11:25:59 rivoplus volumio[3190]: 0+0 records out Sep 08 11:25:59 rivoplus volumio[3190]: 0 bytes copied, 0.000181165 s, 0.0 kB/s Sep 08 11:25:59 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7113, instance 1, run: true Sep 08 11:26:00 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:00 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:00 rivoplus volumio[3190]: 0 bytes copied, 0.000171748 s, 0.0 kB/s Sep 08 11:26:00 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7118, instance 1, run: true Sep 08 11:26:00 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:00 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:00 rivoplus volumio[3190]: 0 bytes copied, 0.000245664 s, 0.0 kB/s Sep 08 11:26:00 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7123, instance 1, run: true Sep 08 11:26:01 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:01 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:01 rivoplus volumio[3190]: 0 bytes copied, 0.000172832 s, 0.0 kB/s Sep 08 11:26:01 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7128, instance 1, run: true Sep 08 11:26:01 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:01 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:01 rivoplus volumio[3190]: 0 bytes copied, 0.00018629 s, 0.0 kB/s Sep 08 11:26:01 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7133, instance 1, run: true Sep 08 11:26:02 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:02 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:02 rivoplus volumio[3190]: 0 bytes copied, 0.000177915 s, 0.0 kB/s Sep 08 11:26:02 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7138, instance 1, run: true Sep 08 11:26:02 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:02 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:02 rivoplus volumio[3190]: 0 bytes copied, 0.000168623 s, 0.0 kB/s Sep 08 11:26:02 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7143, instance 1, run: true Sep 08 11:26:03 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:03 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:03 rivoplus volumio[3190]: 0 bytes copied, 0.000186999 s, 0.0 kB/s Sep 08 11:26:03 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7148, instance 1, run: true Sep 08 11:26:03 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:03 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:03 rivoplus volumio[3190]: 0 bytes copied, 0.000177456 s, 0.0 kB/s Sep 08 11:26:03 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7153, instance 1, run: true Sep 08 11:26:04 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:04 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:04 rivoplus volumio[3190]: 0 bytes copied, 0.000179373 s, 0.0 kB/s Sep 08 11:26:04 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7158, instance 1, run: true Sep 08 11:26:05 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:05 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:05 rivoplus volumio[3190]: 0 bytes copied, 0.000187373 s, 0.0 kB/s Sep 08 11:26:05 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7163, instance 1, run: true Sep 08 11:26:05 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:05 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:05 rivoplus volumio[3190]: 0 bytes copied, 0.000232581 s, 0.0 kB/s Sep 08 11:26:05 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7168, instance 1, run: true Sep 08 11:26:06 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:06 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:06 rivoplus volumio[3190]: 0 bytes copied, 0.00017454 s, 0.0 kB/s Sep 08 11:26:06 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7173, instance 1, run: true Sep 08 11:26:06 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:06 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:06 rivoplus volumio[3190]: 0 bytes copied, 0.000185332 s, 0.0 kB/s Sep 08 11:26:06 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7195, instance 1, run: true Sep 08 11:26:07 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:07 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:07 rivoplus volumio[3190]: 0 bytes copied, 0.000188582 s, 0.0 kB/s Sep 08 11:26:07 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7200, instance 1, run: true Sep 08 11:26:07 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:07 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:07 rivoplus volumio[3190]: 0 bytes copied, 0.000169415 s, 0.0 kB/s Sep 08 11:26:07 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7205, instance 1, run: true Sep 08 11:26:08 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:08 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:08 rivoplus volumio[3190]: 0 bytes copied, 0.00017904 s, 0.0 kB/s Sep 08 11:26:08 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7210, instance 1, run: true Sep 08 11:26:08 rivoplus volumio[3190]: verbose: New Socket.io Connection to 192.168.0.140 from 192.168.0.47 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 9 Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetState Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 08 11:26:08 rivoplus volumio[3190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetVisibleSources Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreCommandRouter::volumioGetQueue Sep 08 11:26:08 rivoplus volumio[3190]: info: CoreStateMachine::getQueue Sep 08 11:26:08 rivoplus volumio[3190]: info: CorePlayQueue::getQueue Sep 08 11:26:08 rivoplus volumio[3190]: info: Listing playlists Sep 08 11:26:08 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:08 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:08 rivoplus volumio[3190]: 0 bytes copied, 0.000187998 s, 0.0 kB/s Sep 08 11:26:08 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7215, instance 1, run: true Sep 08 11:26:09 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:09 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:09 rivoplus volumio[3190]: 0 bytes copied, 0.00018279 s, 0.0 kB/s Sep 08 11:26:09 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7220, instance 1, run: true Sep 08 11:26:09 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:09 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:09 rivoplus volumio[3190]: 0 bytes copied, 0.000197707 s, 0.0 kB/s Sep 08 11:26:10 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7225, instance 1, run: true Sep 08 11:26:10 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:10 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:10 rivoplus volumio[3190]: 0 bytes copied, 0.000193749 s, 0.0 kB/s Sep 08 11:26:10 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7230, instance 1, run: true Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:10 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:10 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:11 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:11 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:11 rivoplus volumio[3190]: 0 bytes copied, 0.000181415 s, 0.0 kB/s Sep 08 11:26:11 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7235, instance 1, run: true Sep 08 11:26:11 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:11 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:11 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:11 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:11 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , setAudioOutputVolume Sep 08 11:26:11 rivoplus volumio[3190]: error: MRS: impossible to set browserPlayback volume: device not found Sep 08 11:26:11 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:11 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:11 rivoplus volumio[3190]: 0 bytes copied, 0.000168166 s, 0.0 kB/s Sep 08 11:26:11 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7240, instance 1, run: true Sep 08 11:26:12 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:12 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:12 rivoplus volumio[3190]: 0 bytes copied, 0.000195457 s, 0.0 kB/s Sep 08 11:26:12 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7245, instance 1, run: true Sep 08 11:26:12 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:12 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:12 rivoplus volumio[3190]: 0 bytes copied, 0.000197915 s, 0.0 kB/s Sep 08 11:26:12 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7250, instance 1, run: true Sep 08 11:26:13 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:13 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:13 rivoplus volumio[3190]: 0 bytes copied, 0.000171915 s, 0.0 kB/s Sep 08 11:26:13 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7255, instance 1, run: true Sep 08 11:26:13 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:13 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:13 rivoplus volumio[3190]: 0 bytes copied, 0.000192332 s, 0.0 kB/s Sep 08 11:26:13 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7260, instance 1, run: true Sep 08 11:26:14 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:14 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:14 rivoplus volumio[3190]: 0 bytes copied, 0.000243956 s, 0.0 kB/s Sep 08 11:26:14 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7265, instance 1, run: true Sep 08 11:26:14 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:14 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:14 rivoplus volumio[3190]: 0 bytes copied, 0.000176873 s, 0.0 kB/s Sep 08 11:26:14 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7271, instance 1, run: true Sep 08 11:26:15 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:15 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:15 rivoplus volumio[3190]: 0 bytes copied, 0.000174999 s, 0.0 kB/s Sep 08 11:26:15 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7276, instance 1, run: true Sep 08 11:26:15 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:15 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:15 rivoplus volumio[3190]: 0 bytes copied, 0.000184207 s, 0.0 kB/s Sep 08 11:26:15 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7281, instance 1, run: true Sep 08 11:26:16 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:16 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:16 rivoplus volumio[3190]: 0 bytes copied, 0.000190415 s, 0.0 kB/s Sep 08 11:26:16 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7286, instance 1, run: true Sep 08 11:26:16 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:16 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:16 rivoplus volumio[3190]: 0 bytes copied, 0.000181457 s, 0.0 kB/s Sep 08 11:26:16 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7308, instance 1, run: true Sep 08 11:26:17 rivoplus volumio[3190]: 0+0 records in Sep 08 11:26:17 rivoplus volumio[3190]: 0+0 records out Sep 08 11:26:17 rivoplus volumio[3190]: 0 bytes copied, 0.000187082 s, 0.0 kB/s Sep 08 11:26:17 rivoplus volumio[3190]: info: camilladsp spawned new process with pid 7313, instance 1, run: true Sep 08 11:26:17 rivoplus volumio[3190]: info: CoreCommandRouter::executeOnPlugin: multiroom , audioOutputPlay Sep 08 11:26:17 rivoplus volumio[3190]: info: Error : CoreCommandRouter::executeOnPlugin: No method [audioOutputPlay] in plugin multiroom Sep 08 11:26:17 rivoplus volumio[3190]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:26:17 rivoplus volumio[3190]: TypeError: Cannot read property 'then' of undefined Sep 08 11:26:17 rivoplus volumio[3190]: at outputs.audioOutputPlay (/volumio/app/plugins/audio_interface/outputs/index.js:367:9) Sep 08 11:26:17 rivoplus volumio[3190]: at CoreCommandRouter.audioOutputPlay (/volumio/app/index.js:2279:30) Sep 08 11:26:17 rivoplus volumio[3190]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1456:26) Sep 08 11:26:17 rivoplus volumio[3190]: at Socket.emit (events.js:400:28) Sep 08 11:26:17 rivoplus volumio[3190]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 08 11:26:17 rivoplus volumio[3190]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 08 11:26:17 rivoplus volumio[3190]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 08 11:26:18 rivoplus sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-08 11:25 Sep 08 11:26:18 rivoplus sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"