I got the advice to separate my audio network from the router.
So I added a cheap switch between the router and Roon core and the streamer. See diagram.
The hypothesis is that the Fritz!Box router has not the best quality parts inside and that one should only let it do the routing and use a separate switch for that switching duties and also separate wifi from the router. I will do the latter in a next step.
This seems to make a difference as I did not have a dropout since Thursday night and I listened to music many hours every day. So I believe this is an improvement, because normally there would have been more dropouts - but it could also be merely coincidence, although rather unlikely, I think.
Unfortunately, I just had a droput again (log file 11:57). But I wonder if this was a disconnect by/to Qobuz or my particular dropout problem in my home network.
Maybe someone more knowledgable than me can chime in and interpret this log file? Many thanks!
06/19 11:53:14 Trace: [Yggdrasil] [Enhanced 70,9x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:16/8:20] Aftermath 3 - AES Dana
06/19 11:53:19 Trace: [Yggdrasil] [Enhanced 70,8x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:21/8:20] Aftermath 3 - AES Dana
06/19 11:53:21 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17474201602856 rtt=500us offset=11585678602us delta=-15us drift=-16083us in 939,7555s (-17,115ppm, -61,613ms/hr)
06/19 11:53:24 Trace: [Yggdrasil] [Enhanced 71,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:26/8:20] Aftermath 3 - AES Dana
06/19 11:53:26 Info: [stats] 7307mb Virtual, 1225mb Physical, 393mb Managed, 2842 Handles, 189 Threads
06/19 11:53:29 Trace: [Yggdrasil] [Enhanced 71,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:31/8:20] Aftermath 3 - AES Dana
06/19 11:53:34 Trace: [Yggdrasil] [Enhanced 71,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:36/8:20] Aftermath 3 - AES Dana
06/19 11:53:39 Trace: [Yggdrasil] [Enhanced 71,1x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:41/8:20] Aftermath 3 - AES Dana
06/19 11:53:41 Info: [stats] 7307mb Virtual, 1225mb Physical, 395mb Managed, 2841 Handles, 189 Threads
06/19 11:53:44 Trace: [Yggdrasil] [Enhanced 71,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:46/8:20] Aftermath 3 - AES Dana
06/19 11:53:50 Trace: [Yggdrasil] [Enhanced 71,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:52/8:20] Aftermath 3 - AES Dana
06/19 11:53:51 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17504585984678 rtt=500us offset=11585676984us delta=-244us drift=-17701us in 970,1415s (-18,247ppm, -65,688ms/hr)
06/19 11:53:55 Trace: [Yggdrasil] [Enhanced 71,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:57/8:20] Aftermath 3 - AES Dana
06/19 11:53:56 Info: [stats] 7307mb Virtual, 1225mb Physical, 399mb Managed, 2841 Handles, 189 Threads
06/19 11:54:00 Trace: [Yggdrasil] [Enhanced 71,4x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:02/8:20] Aftermath 3 - AES Dana
06/19 11:54:05 Trace: [Yggdrasil] [Enhanced 71,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:07/8:20] Aftermath 3 - AES Dana
06/19 11:54:10 Trace: [Yggdrasil] [Enhanced 71,5x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:12/8:20] Aftermath 3 - AES Dana
06/19 11:54:11 Info: [stats] 7307mb Virtual, 1225mb Physical, 400mb Managed, 2841 Handles, 189 Threads
06/19 11:54:15 Trace: [Yggdrasil] [Enhanced 71,5x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:17/8:20] Aftermath 3 - AES Dana
06/19 11:54:20 Trace: [Yggdrasil] [Enhanced 71,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:22/8:20] Aftermath 3 - AES Dana
06/19 11:54:22 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17535031395850 rtt=0us offset=11585676395us delta=-72us drift=-18290us in 1000,588s (-18,280ppm, -65,807ms/hr)
06/19 11:54:25 Trace: [Yggdrasil] [Enhanced 71,7x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:27/8:20] Aftermath 3 - AES Dana
06/19 11:54:26 Info: [stats] 7307mb Virtual, 1225mb Physical, 404mb Managed, 2841 Handles, 189 Threads
06/19 11:54:30 Trace: [Yggdrasil] [Enhanced 71,7x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:32/8:20] Aftermath 3 - AES Dana
06/19 11:54:35 Trace: [Yggdrasil] [Enhanced 71,8x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:37/8:20] Aftermath 3 - AES Dana
06/19 11:54:41 Info: [stats] 7311mb Virtual, 1225mb Physical, 390mb Managed, 2846 Handles, 190 Threads
06/19 11:54:41 Trace: [Yggdrasil] [Enhanced 71,9x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:43/8:20] Aftermath 3 - AES Dana
06/19 11:54:46 Trace: [Yggdrasil] [Enhanced 71,9x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:48/8:20] Aftermath 3 - AES Dana
06/19 11:54:51 Trace: [Yggdrasil] [Enhanced 72,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:53/8:20] Aftermath 3 - AES Dana
06/19 11:54:52 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17565210522302 rtt=500us offset=11585675522us delta=-143us drift=-19164us in 1030,7675s (-18,592ppm, -66,931ms/hr)
06/19 11:54:54 Trace: [broker/accounts] [heartbeat] now=19.06.2022 09:54:53 nextauthrefresh=19.06.2022 10:19:54 nextmachineallocate=19.06.2022 12:14:53
06/19 11:54:56 Info: [stats] 7311mb Virtual, 1225mb Physical, 391mb Managed, 2846 Handles, 190 Threads
06/19 11:54:56 Trace: [Yggdrasil] [Enhanced 72,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:58/8:20] Aftermath 3 - AES Dana
06/19 11:55:01 Trace: [Yggdrasil] [Enhanced 72,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:03/8:20] Aftermath 3 - AES Dana
06/19 11:55:06 Trace: [Yggdrasil] [Enhanced 72,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:08/8:20] Aftermath 3 - AES Dana
06/19 11:55:11 Info: [stats] 7307mb Virtual, 1225mb Physical, 397mb Managed, 2843 Handles, 189 Threads
06/19 11:55:11 Trace: [Yggdrasil] [Enhanced 72,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:13/8:20] Aftermath 3 - AES Dana
06/19 11:55:16 Trace: [Yggdrasil] [Enhanced 72,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:18/8:20] Aftermath 3 - AES Dana
06/19 11:55:21 Trace: [Yggdrasil] [Enhanced 72,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:23/8:20] Aftermath 3 - AES Dana
06/19 11:55:22 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17595523105149 rtt=0us offset=11585675105us delta=-732us drift=-19581us in 1061,081s (-18,454ppm, -66,435ms/hr)
06/19 11:55:26 Info: [stats] 7307mb Virtual, 1225mb Physical, 395mb Managed, 2843 Handles, 189 Threads
06/19 11:55:26 Trace: [Yggdrasil] [Enhanced 72,4x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:28/8:20] Aftermath 3 - AES Dana
06/19 11:55:32 Trace: [Yggdrasil] [Enhanced 72,5x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:34/8:20] Aftermath 3 - AES Dana
06/19 11:55:37 Trace: [Yggdrasil] [Enhanced 72,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:39/8:20] Aftermath 3 - AES Dana
06/19 11:55:41 Info: [stats] 7307mb Virtual, 1225mb Physical, 398mb Managed, 2843 Handles, 189 Threads
06/19 11:55:42 Trace: [Yggdrasil] [Enhanced 72,5x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:44/8:20] Aftermath 3 - AES Dana
06/19 11:55:47 Trace: [Yggdrasil] [Enhanced 72,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:48/8:20] Aftermath 3 - AES Dana
06/19 11:55:52 Trace: [Yggdrasil] [Enhanced 72,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:54/8:20] Aftermath 3 - AES Dana
06/19 11:55:53 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17625988742321 rtt=500us offset=11585674742us delta=566us drift=-19944us in 1091,5465s (-18,271ppm, -65,777ms/hr)
06/19 11:55:56 Info: [stats] 7307mb Virtual, 1225mb Physical, 397mb Managed, 2843 Handles, 189 Threads
06/19 11:55:57 Trace: [Yggdrasil] [Enhanced 72,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 3:59/8:20] Aftermath 3 - AES Dana
06/19 11:56:02 Trace: [Yggdrasil] [Enhanced 72,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:04/8:20] Aftermath 3 - AES Dana
06/19 11:56:07 Trace: [Yggdrasil] [Enhanced 72,7x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:09/8:20] Aftermath 3 - AES Dana
06/19 11:56:11 Info: [stats] 7307mb Virtual, 1225mb Physical, 398mb Managed, 2841 Handles, 189 Threads
06/19 11:56:12 Trace: [Yggdrasil] [Enhanced 72,8x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:14/8:20] Aftermath 3 - AES Dana
06/19 11:56:18 Trace: [Yggdrasil] [Enhanced 72,7x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:20/8:20] Aftermath 3 - AES Dana
06/19 11:56:23 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17656284875162 rtt=500us offset=11585673875us delta=-164us drift=-20811us in 1121,8435s (-18,551ppm, -66,783ms/hr)
06/19 11:56:23 Trace: [Yggdrasil] [Enhanced 72,9x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:25/8:20] Aftermath 3 - AES Dana
06/19 11:56:26 Info: [stats] 7311mb Virtual, 1225mb Physical, 390mb Managed, 2846 Handles, 190 Threads
06/19 11:56:28 Trace: [Yggdrasil] [Enhanced 73,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:30/8:20] Aftermath 3 - AES Dana
06/19 11:56:33 Trace: [Yggdrasil] [Enhanced 72,9x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:35/8:20] Aftermath 3 - AES Dana
06/19 11:56:38 Trace: [Yggdrasil] [Enhanced 73,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:40/8:20] Aftermath 3 - AES Dana
06/19 11:56:41 Info: [stats] 7307mb Virtual, 1225mb Physical, 391mb Managed, 2841 Handles, 189 Threads
06/19 11:56:43 Trace: [Yggdrasil] [Enhanced 73,1x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:45/8:20] Aftermath 3 - AES Dana
06/19 11:56:48 Trace: [Yggdrasil] [Enhanced 73,1x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:50/8:20] Aftermath 3 - AES Dana
06/19 11:56:53 Trace: [Yggdrasil] [Enhanced 73,1x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 4:55/8:20] Aftermath 3 - AES Dana
06/19 11:56:53 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17686684983338 rtt=500us offset=11585672983us delta=-255us drift=-21703us in 1152,2445s (-18,835ppm, -67,808ms/hr)
06/19 11:56:56 Info: [stats] 7307mb Virtual, 1225mb Physical, 395mb Managed, 2841 Handles, 189 Threads
06/19 11:56:58 Trace: [Yggdrasil] [Enhanced 73,2x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:00/8:20] Aftermath 3 - AES Dana
06/19 11:57:03 Trace: [Yggdrasil] [Enhanced 73,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:05/8:20] Aftermath 3 - AES Dana
06/19 11:57:09 Trace: [Yggdrasil] [Enhanced 73,3x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:11/8:20] Aftermath 3 - AES Dana
06/19 11:57:11 Info: [stats] 7307mb Virtual, 1225mb Physical, 395mb Managed, 2841 Handles, 189 Threads
06/19 11:57:14 Trace: [Yggdrasil] [Enhanced 73,4x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:16/8:20] Aftermath 3 - AES Dana
06/19 11:57:19 Trace: [Yggdrasil] [Enhanced 73,5x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:21/8:20] Aftermath 3 - AES Dana
06/19 11:57:24 Trace: [Yggdrasil] [zoneplayer/raat] sync Sonore opticalRendu: realtime=17717158296177 rtt=500us offset=11585672296us delta=-1027us drift=-22390us in 1182,7185s (-18,931ppm, -68,152ms/hr)
06/19 11:57:24 Trace: [Yggdrasil] [Enhanced 73,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:26/8:20] Aftermath 3 - AES Dana
06/19 11:57:26 Info: [stats] 7307mb Virtual, 1225mb Physical, 397mb Managed, 2841 Handles, 189 Threads
06/19 11:57:29 Trace: [Yggdrasil] [Enhanced 73,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:31/8:20] Aftermath 3 - AES Dana
06/19 11:57:33 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":261}
06/19 11:57:34 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:34 Trace: [Yggdrasil] [Enhanced 73,6x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 5:36/8:20] Aftermath 3 - AES Dana
06/19 11:57:34 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:35 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:35 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:36 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:36 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Dropout","samples":22060}
06/19 11:57:36 Warn: [Yggdrasil] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/19 11:57:36 Trace: [Yggdrasil] [zoneplayer/raat] too many dropouts. stopping stream
06/19 11:57:36 Debug: FTMSI-B closed file for qo/678126CD; open files:0
06/19 11:57:36 Debug: FTMSI-B closed file for qo/3A6976B9; open files:0
06/19 11:57:36 Debug: FTMSI-B qo/678126CD download status: AllBlocksDownloaded accessTimeout:True openFiles:0 prev:(AllBlocksDownloaded,True,1)
06/19 11:57:36 Debug: FTMSI-B qo/3A6976B9 download status: AllBlocksDownloaded accessTimeout:False openFiles:0 prev:(AllBlocksDownloaded,False,1)
06/19 11:57:36 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/19 11:57:36 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Playing => Prepared
06/19 11:57:36 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [65]{"request":"end_stream"}
06/19 11:57:36 Debug: [raat/tcpaudiosource] disconnecting
06/19 11:57:36 Info: [audio/env] [zoneplayer] All streams were disposed
06/19 11:57:36 Warn: [zone Yggdrasil] Track Stopped Due to Slow Media
06/19 11:57:36 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/19 11:57:36 Warn: [raat/tcpaudiosource] send failed: Der Objektverweis wurde nicht auf eine Objektinstanz festgelegt.
06/19 11:57:36 Warn: [raat/tcpaudiosource] disconnecting + retrying
06/19 11:57:36 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [63] {"status":"Ended"}
06/19 11:57:36 Debug: Lastfm 'fpBZV_fac0DqdWWD0HHUGrgUqxqaOyu6' DONE: AES Dana - Aftermath 3
06/19 11:57:36 Info: [library] recorded play for profile d70799f7-1950-45c5-8491-9831afce625b: mediaid=202:0:23686938 metadataid= contentid=202:0:23686938 libraryid= isfromradio=False isfrommix=False isfrommobile=
06/19 11:57:36 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Inactive
Elements:
------------------------------------------------------------
06/19 11:57:36 Warn: inactive signal path :(
06/19 11:57:36 Info: [zone Yggdrasil] OnPlayFeedback StoppedEndOfMediaUnnatural
06/19 11:57:36 Debug: [zone Yggdrasil] _Advance
06/19 11:57:36 Trace: [Yggdrasil] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [LOADING @ 0:00] Culture - Tikal
06/19 11:57:36 Info: Alert: qobuz: qobuz_media_slow
06/19 11:57:36 Warn: AddTopLevel: win_alert(10153)
06/19 11:57:36 Info: Alert: qobuz: qobuz_media_slow
06/19 11:57:36 Info: sleep 50ms after flush
06/19 11:57:36 Info: [Yggdrasil] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
06/19 11:57:36 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [65] {"status":"Success"}
06/19 11:57:36 Debug: [easyhttp] [1536] POST to http://ws.audioscrobbler.com/2.0/ returned after 170 ms, status code: 200
06/19 11:57:36 Debug: [easyhttp] [1538] GET to https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&intent=stream&request_sig=92de59b00027e23ecb05da99183461d6&request_ts=1655632656&track_id=23686940 returned after 195 ms, status code: 200
06/19 11:57:36 Debug: [easyhttp] [1537] GET to https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&intent=stream&request_sig=050efba2017acff6df4e7fe8f989a5c7&request_ts=1655632656&track_id=23686939 returned after 317 ms, status code: 200
06/19 11:57:36 Info: [Yggdrasil] [zoneplayer] Playing: https://streaming-qobuz-std.akamaized.net/file
06/19 11:57:36 Info: [Yggdrasil] [zoneplayer] Queueing: https://streaming-qobuz-std.akamaized.net/file
06/19 11:57:36 Info: FTMSI-B new FileCache qo/113BCC19
>>> C:\Users\ \AppData\Local\Roon\Cache\smc.db\bfc\13.cache
>>> https://streaming-qobuz-std.akamaized.net/file
06/19 11:57:36 Debug: FTMSI-B Cache open file qo/113BCC19 domain: zoneplayer:1 ordinal:35
06/19 11:57:36 Debug: FTMSI-B qo/113BCC19 download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
06/19 11:57:36 Info: FTMSI-B qo/113BCC19: allocated bw changed from 0 to 51200 kbps
06/19 11:57:36 Info: FTMSI-B 1 FileCache qo/113BCC19 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:1':35) --> bw limit:51200kbps
06/19 11:57:36 Info: FTMSI-B 2 FileCache qo/3A6976B9 dwStatus:AllBlocksDownloaded files:0 accessTimeOut:False priorities: ('zoneplayer:1':33) --> bw limit:0kbps
06/19 11:57:36 Info: FTMSI-B 3 FileCache qo/678126CD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:1':34) --> bw limit:0kbps
06/19 11:57:36 Info: FTMSI-B FileCache removed qo/3A6976B9
06/19 11:57:36 Debug: FTMSI-B-OE qo/3A6976B9 exit thread signalled
06/19 11:57:36 Trace: FTMSI-B 1 FileCache qo/113BCC19 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:1':35) --> bw limit:51200kbps
06/19 11:57:36 Debug: FTMSI-B-OE qo/3A6976B9 exit thread signalled
06/19 11:57:36 Trace: FTMSI-B 2 FileCache qo/678126CD dwStatus:AllBlocksDownloaded files:0 accessTimeOut:True priorities: ('zoneplayer:1':34) --> bw limit:0kbps
06/19 11:57:36 Debug: FTMSI-B-OE qo/113BCC19 created new req 1 for block 0 p 4294967295; active requests 1
06/19 11:57:36 Info: FTMSI-B FileCache disposed qo/3A6976B9
06/19 11:57:36 Info: FTMSI-B new FileCache qo/A47AE575
>>> C:\Users\ \AppData\Local\Roon\Cache\smc.db\bfc\14.cache
>>> https://streaming-qobuz-std.akamaized.net/file
06/19 11:57:36 Debug: FTMSI-B Cache open file qo/A47AE575 domain: zoneplayer:1 ordinal:36
06/19 11:57:36 Debug: FTMSI-B qo/A47AE575 download status: DownloadNotStarted accessTimeout:False openFiles:1 prev:no
06/19 11:57:36 Info: FTMSI-B FileCache removed qo/678126CD
06/19 11:57:36 Debug: FTMSI-B-OE qo/678126CD exit thread signalled
06/19 11:57:36 Trace: FTMSI-B 1 FileCache qo/113BCC19 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:1':35) --> bw limit:51200kbps
06/19 11:57:36 Trace: FTMSI-B 2 FileCache qo/A47AE575 dwStatus:DownloadNotStarted files:1 accessTimeOut:False priorities: ('zoneplayer:1':36) --> bw limit:0kbps
06/19 11:57:36 Debug: FTMSI-B-OE qo/678126CD exit thread signalled
06/19 11:57:36 Info: FTMSI-B FileCache disposed qo/678126CD
06/19 11:57:36 Debug: [easyhttp] [1547] GET to https://streaming-qobuz-std.akamaized.net/file?uid=1488110&eid=23686939&fmt=6&profile=raw&app_id=188245549&cid=1395411&etsp=1655636255&hmac=smxaAhNT8j2PDp9j25dx0s-NHVs returned after 73 ms, status code: 200
06/19 11:57:36 Debug: FTMSI-B got length for qo/113BCC19; 55,1 MBytes
06/19 11:57:36 Debug: FTMSI-B qo/113BCC19 download status: FileLengthRetrieved accessTimeout:False openFiles:1 prev:(DownloadNotStarted,False,1)
06/19 11:57:36 Debug: FTMSI-B-OE set min bandwidth for qo/113BCC19 to 1048 kbps
06/19 11:57:36 Info: FTMSI-B-OE qo/113BCC19 rid:1 response took 75ms
06/19 11:57:36 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FileLengthRetrieved,False,1)
06/19 11:57:36 Debug: [easyhttp] [1535] POST to https://www.qobuz.com/api.json/0.2/track/reportStreamingEnd? returned after 420 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1539] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2?c=tidal-de,qobuz-de returned after 451 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1544] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/relationships?c=tidal-de,qobuz-de returned after 454 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1540] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/relationships?c=tidal-de,qobuz-de returned after 457 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1546] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/biographies?c=tidal-de,qobuz-de returned after 468 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1543] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2?c=tidal-de,qobuz-de returned after 470 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1545] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/refs?c=tidal-de,qobuz-de returned after 470 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1542] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/biographies?c=tidal-de,qobuz-de returned after 471 ms, status code: 200
06/19 11:57:37 Debug: [easyhttp] [1541] GET to https://metadata.roonlabs.net/1/performers/94:1:056803e7-7f67-4b0c-b5a8-57ecef7bd3f2/refs?c=tidal-de,qobuz-de returned after 518 ms, status code: 200
06/19 11:57:37 Debug: GMS: saving nav stack
06/19 11:57:37 Debug: GMS: trying to save nav stack, but nav stack stuff was in progress
06/19 11:57:37 Debug: GMS: done saving nav stack
06/19 11:57:37 Info: [Yggdrasil] [zoneplayer] Open Result (Playing):Result[Status=Success]
06/19 11:57:37 Info: [Yggdrasil] [zoneplayer] Starting playback
06/19 11:57:37 Debug: [convolution] adjusted filter length from 87586 to 87562 since filter had trailing zeroes
06/19 11:57:37 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/517 sec
06/19 11:57:37 Debug: [convolution] performing convolution with 4 paths
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=32, samplerate=44100, isdts=False) streamid=1096490366
06/19 11:57:37 Debug: Lastfm 'fpBZV_fac0DqdWWD0HHUGrgUqxqaOyu6' START: Tikal - Culture
06/19 11:57:37 Info: [zone Yggdrasil] OnPlayFeedback Playing
06/19 11:57:37 Trace: [Yggdrasil] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:00] Culture - Tikal
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] synced to endpoint Sonore opticalRendu clock: realtime=17730123681197 rtt=500us offset=11585671681us delta=11585671681us
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [66]{"request":"stream","stream_id":1096490366,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [66] {"status":"Buffering"}
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Prepared => Buffering
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] [zoneplayer/raat] selected Sonore opticalRendu as clock master
06/19 11:57:37 Debug: [easyhttp] [1548] POST to https://www.qobuz.com/api.json/0.2/track/reportStreamingStart? returned after 102 ms, status code: 201
06/19 11:57:37 Debug: [raat/tcpaudiosource] connecting to 192.168.178.39:34779
06/19 11:57:37 Debug: [raat/tcpaudiosource] connected
06/19 11:57:37 Debug: [easyhttp] [1549] POST to http://ws.audioscrobbler.com/2.0/ returned after 141 ms, status code: 200
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [66] {"status":"Ready"}
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Buffering => Ready
06/19 11:57:37 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
Convolution
Truncate FromBitsPerSample=64 ToBitsPerSample=32 Quality=Lossless
Raat Device=Sonore opticalRendu
Output OutputType=Local_Usb Quality=Lossless SubType= Model=
------------------------------------------------------------
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] wait for ready in 0ms
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] Adjusting playback start offset from 50ms to 70ms
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [67]{"request":"start","min_offset":70929705,"stream_sample":0}
06/19 11:57:37 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Ready => Playing
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [66] {"status":"Playing"}
06/19 11:57:37 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [67] {"status":"Success","time":17730738810335}
06/19 11:57:38 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:True openFiles:1 prev:(FirstBlockRetrieved,False,1)
06/19 11:57:39 Debug: GETKEY. KeyToChar(Capital, False, False) ->
06/19 11:57:39 Debug: GETKEY. KeyToChar(Capital, False, False) ->
06/19 11:57:40 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FirstBlockRetrieved,True,1)
06/19 11:57:41 Info: [stats] 7335mb Virtual, 1226mb Physical, 427mb Managed, 2924 Handles, 196 Threads
06/19 11:57:41 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:True openFiles:1 prev:(FirstBlockRetrieved,False,1)
06/19 11:57:42 Debug: UI-NAV: queue
06/19 11:57:42 Debug: UI-FORCE-LAZY: mode: nowplaying
06/19 11:57:42 Debug: UI-FORCE-LAZY: mode: artistdetails
06/19 11:57:42 Debug: GMS: saving nav stack
06/19 11:57:42 Debug: UI-NAV: queue
06/19 11:57:42 Debug: unhandled selection type:
06/19 11:57:42 Debug: GMS: done saving nav stack
06/19 11:57:42 Trace: [Yggdrasil] [Enhanced 67,0x, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:04/8:37] Culture - Tikal
06/19 11:57:42 Trace: GetImageData[Remote](id=3960 spec=256 key=iwfaaaaa uri=https://imagecache.roonlabs.net/im/1/albums/79004d5730303032363036383136/cover/256.jpg) => fetched in 64ms status=304 size=0 overalltime=66ms
06/19 11:57:42 Debug: [easyhttp] [1550] GET to http://127.0.0.1:9300/image/iwfaaaaa.256.jpg returned after 72 ms, status code: 304
06/19 11:57:44 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FirstBlockRetrieved,True,1)
06/19 11:57:45 Info: [ui] [popups] Pause button clicked: play_pause()
06/19 11:57:45 Trace: [zone Yggdrasil] PlayPause
06/19 11:57:45 Trace: [zone Yggdrasil] Pause
06/19 11:57:45 Info: [zone Yggdrasil] Canceling Pending Sleep
06/19 11:57:45 Trace: [Yggdrasil] [zoneplayer/raat] Pausing at streamtime_ns 7666275031 now_ns 17738405085366
06/19 11:57:45 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [68]{"request":"stop"}
06/19 11:57:45 Info: [zone Yggdrasil] OnPlayFeedback Paused
06/19 11:57:45 Trace: [Yggdrasil] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PAUSED @ 0:07/8:37] Culture - Tikal
06/19 11:57:45 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Playing => Paused
06/19 11:57:45 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [66] {"status":"Stopped"}
06/19 11:57:45 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:True openFiles:1 prev:(FirstBlockRetrieved,False,1)
06/19 11:57:45 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] GOT [68] {"status":"Success"}
06/19 11:57:50 Trace: [zone Yggdrasil] Suspend
06/19 11:57:50 Trace: [zone Yggdrasil] Stop
06/19 11:57:50 Info: [zone Yggdrasil] OnPlayFeedback Stopped
06/19 11:57:50 Info: [zone Yggdrasil] Canceling Pending Sleep
06/19 11:57:50 Debug: FTMSI-B closed file for qo/113BCC19; open files:0
06/19 11:57:50 Debug: FTMSI-B qo/113BCC19 download status: FirstBlockRetrieved accessTimeout:True openFiles:0 prev:(FirstBlockRetrieved,True,1)
06/19 11:57:50 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/19 11:57:50 Info: FTMSI-B qo/113BCC19: allocated bw changed from 51200 to 0 kbps
06/19 11:57:50 Info: FTMSI-B qo/A47AE575: allocated bw changed from 0 to 51200 kbps
06/19 11:57:50 Info: FTMSI-B 1 FileCache qo/113BCC19 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:1':35) --> bw limit:0kbps
06/19 11:57:50 Info: FTMSI-B 2 FileCache qo/A47AE575 dwStatus:DownloadNotStarted files:1 accessTimeOut:True priorities: ('zoneplayer:1':36) --> bw limit:51200kbps
06/19 11:57:50 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Paused => Prepared
06/19 11:57:50 Info: [audio/env] [zoneplayer] All streams were disposed
06/19 11:57:50 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/19 11:57:50 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [69]{"request":"end_stream"}
06/19 11:57:50 Trace: [Sonore opticalRendu @ 192.168.178.39:36709] [raatclient] SENT [70]{"request":"teardown"}
06/19 11:57:50 Trace: [Yggdrasil] [zoneplayer/raat] Endpoint Sonore opticalRendu State Changed: Prepared => Idle
06/19 11:57:50 Debug: [raat/tcpaudiosource] disconnecting
06/19 11:57:50 Debug: FTMSI-B-OE qo/A47AE575 created new req 1 for block 0 p 4294967295; active requests 1
06/19 11:57:50 Trace: [transport] disabling transport
06/19 11:57:50 Trace: [zone Yggdrasil] Suspend
06/19 11:57:50 Info: [zone Yggdrasil] Canceling Pending Sleep
06/19 11:57:50 Info: [zone Yggdrasil] Canceling Pending Sleep
06/19 11:57:50 Trace: [raat] disposing
06/19 11:57:50 Trace: [airplay] disposing
06/19 11:57:50 Trace: [devialet] disposing
06/19 11:57:50 Trace: [devicemanager/sonos] disposing
06/19 11:57:50 Trace: [devicemanager/kef] disposing
06/19 11:57:50 Trace: [hqplayer] disposing
06/19 11:57:50 Trace: [meridian] disposing
06/19 11:57:50 Info: sleep 50ms after flush
06/19 11:57:50 Debug: [easyhttp] [1554] POST to https://discovery.roonlabs.net/1/unregister returned after 227 ms, status code: 200
06/19 11:57:50 Trace: [inetdiscovery] unregistered 1 devices
06/19 11:57:50 Debug: [easyhttp] [1552] POST to https://www.qobuz.com/api.json/0.2/track/reportStreamingEnd? returned after 263 ms, status code: 200
06/19 11:57:50 Debug: [easyhttp] [1553] GET to https://streaming-qobuz-std.akamaized.net/file?uid=1488110&eid=23686940&fmt=6&profile=raw&app_id=188245549&cid=1395411&etsp=1655636255&hmac=DTlbXknDeiIa_ZuNEXGKQemUXfE returned after 549 ms, status code: 200
06/19 11:57:50 Debug: FTMSI-B got length for qo/A47AE575; 46,6 MBytes
06/19 11:57:50 Debug: FTMSI-B qo/A47AE575 download status: FileLengthRetrieved accessTimeout:True openFiles:1 prev:(DownloadNotStarted,True,1)
06/19 11:57:50 Debug: FTMSI-B-OE set min bandwidth for qo/A47AE575 to 994 kbps
06/19 11:57:50 Info: FTMSI-B-OE qo/A47AE575 rid:1 response took 549ms
06/19 11:57:51 Debug: FTMSI-B qo/A47AE575 download status: FirstBlockRetrieved accessTimeout:False openFiles:1 prev:(FileLengthRetrieved,False,1)
06/19 11:57:51 Info: [Yggdrasil] [zoneplayer] Open result (Queueing): Result[Status=Success]
06/19 11:57:51 Debug: FTMSI-B closed file for qo/A47AE575; open files:0
06/19 11:57:51 Debug: FTMSI-B qo/A47AE575 download status: FirstBlockRetrieved accessTimeout:False openFiles:0 prev:(FirstBlockRetrieved,False,1)
06/19 11:57:51 Info: FTMSI-B qo/A47AE575: allocated bw changed from 51200 to 0 kbps
06/19 11:57:51 Info: FTMSI-B 1 FileCache qo/113BCC19 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:True priorities: ('zoneplayer:1':35) --> bw limit:0kbps
06/19 11:57:51 Info: FTMSI-B 2 FileCache qo/A47AE575 dwStatus:FirstBlockRetrieved files:0 accessTimeOut:False priorities: ('zoneplayer:1':36) --> bw limit:0kbps
06/19 11:57:51 Info: [audio/env] [zoneplayer] All streams were disposed
06/19 11:57:51 Debug: [storage] dispose deleting: C:\Users\ \AppData\Local\Roon\Temp\3932bc4663014cd499e42f4aaaa9738a
06/19 11:57:51 Warn: [storage] [directory] [itunes] dispose !!!!
06/19 11:57:51 Debug: [tidal/storage] dispose