Skip to content

Instantly share code, notes, and snippets.

@arthursoares

arthursoares/log.log Secret

Created Apr 22, 2021
Embed
What would you like to do?
Roon Log
04/22 19:11:15 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":235,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":0,"length":235,"one_line":{"line1":"Mad - Solange / Lil Wayne"},"two_line":{"line1":"Mad","line2":"Solange / Lil Wayne"},"three_line":{"line1":"Mad","line2":"Solange / Lil Wayne","line3":"A Seat at the Table"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3857f16915b948743ffe8af2f6e4a7bb3e22f2236cd1ef046e9bf53fb6c7caa09eaab1b0623ec3afe1d2b44e6418ee730c0c38c397adab8a9038e676bf519c30fdf","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb1837c760c1984b6ea05a5a011df9d58f27fc999fdd94fe81eae92699d6c77458e50a001b2ce6a78d37cfea19322d8af4","4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb049bb2295efb5512dd3a77a9aa16e5575b629f7e58404fdba462b7cfdac1965380e86895a06b18cff8f4f70b213ffb70"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":235,"seek_position":0}]}
04/22 19:11:15 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/4352082?countryCode=DE => Success
04/22 19:11:15 Info: [Receiver HiFi] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMWY2N2NhNDY0YzU0OGE4ODcxMmNkMzk3Y2ZhOGJhNzZfNjEubXA0/0.flac
04/22 19:11:15 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:11:16 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:12/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:16 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:11:19 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=344510830071 rtt=32500us offset=-339536169us delta=29932us drift=31254us in 195.9245s (159.523ppm, 574.282ms/hr)
04/22 19:11:20 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [8% buf] [PLAYING @ 0:04/3:55] Mad - Solange / Lil Wayne
04/22 19:11:21 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:17/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:23 Info: [stats] 4161mb Virtual, 1929mb Physical, 754mb Managed, 0 Handles, 116 Threads
04/22 19:11:25 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [34% buf] [PLAYING @ 0:09/3:55] Mad - Solange / Lil Wayne
04/22 19:11:26 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:22/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:29 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=354615295907 rtt=42500us offset=-339526704us delta=38492us drift=40720us in 206.0095s (197.662ppm, 711.583ms/hr)
04/22 19:11:30 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [40% buf] [PLAYING @ 0:15/3:55] Mad - Solange / Lil Wayne
04/22 19:11:32 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:28/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:35 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [48% buf] [PLAYING @ 0:20/3:55] Mad - Solange / Lil Wayne
04/22 19:11:37 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:33/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:37 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=362590944660 rtt=2000us offset=-339566055us delta=375us drift=1369us in 214.065s (6.395ppm, 23.023ms/hr)
04/22 19:11:38 Info: [stats] 4161mb Virtual, 1929mb Physical, 757mb Managed, 0 Handles, 116 Threads
04/22 19:11:40 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [38% buf] [PLAYING @ 0:25/3:55] Mad - Solange / Lil Wayne
04/22 19:11:42 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:38/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:45 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=393115249942 rtt=1000us offset=-88643750us delta=443us drift=-1848us in 30.206s (-61.192ppm, -220.289ms/hr)
04/22 19:11:45 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [54% buf] [PLAYING @ 0:30/3:55] Mad - Solange / Lil Wayne
04/22 19:11:47 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:43/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:51 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [40% buf] [PLAYING @ 0:35/3:55] Mad - Solange / Lil Wayne
04/22 19:11:52 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:48/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:53 Info: [stats] 4161mb Virtual, 1921mb Physical, 763mb Managed, 0 Handles, 116 Threads
04/22 19:11:54 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=378959499821 rtt=73000us offset=-339497500us delta=68093us drift=69924us in 230.294s (303.630ppm, 1093.068ms/hr)
04/22 19:11:56 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=380828737617 rtt=41500us offset=-339527262us delta=-29762us drift=40161us in 232.2245s (172.945ppm, 622.600ms/hr)
04/22 19:11:56 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [44% buf] [PLAYING @ 0:40/3:55] Mad - Solange / Lil Wayne
04/22 19:11:57 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:53/6:11] O Estrangeiro - Caetano Veloso
04/22 19:11:58 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=382945213868 rtt=59500us offset=-339509786us delta=17476us drift=57638us in 234.3055s (245.996ppm, 885.586ms/hr)
04/22 19:12:01 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [34% buf] [PLAYING @ 0:46/3:55] Mad - Solange / Lil Wayne
04/22 19:12:02 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 3:57/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:06 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [28% buf] [PLAYING @ 0:51/3:55] Mad - Solange / Lil Wayne
04/22 19:12:07 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:03/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:08 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=392915851744 rtt=1500us offset=-339566148us delta=-8us drift=1276us in 244.389s (5.222ppm, 18.798ms/hr)
04/22 19:12:08 Info: [stats] 4161mb Virtual, 1921mb Physical, 766mb Managed, 0 Handles, 116 Threads
04/22 19:12:11 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [18% buf] [PLAYING @ 0:56/3:55] Mad - Solange / Lil Wayne
04/22 19:12:12 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:08/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:14 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=399037528576 rtt=31000us offset=-339537471us delta=29184us drift=29952us in 250.454s (119.594ppm, 430.540ms/hr)
04/22 19:12:15 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=423318635164 rtt=1000us offset=-88644364us delta=-659us drift=-2463us in 60.41s (-40.774ppm, -146.785ms/hr)
04/22 19:12:17 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:13/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:17 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [10% buf] [PLAYING @ 1:01/3:55] Mad - Solange / Lil Wayne
04/22 19:12:22 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:18/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:22 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [8% buf] [PLAYING @ 1:06/3:55] Mad - Solange / Lil Wayne
04/22 19:12:23 Info: [stats] 4161mb Virtual, 1921mb Physical, 772mb Managed, 0 Handles, 116 Threads
04/22 19:12:26 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=411246355288 rtt=50500us offset=-339517644us delta=41898us drift=49779us in 262.6235s (189.548ppm, 682.371ms/hr)
04/22 19:12:27 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:23/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:27 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [10% buf] [PLAYING @ 1:12/3:55] Mad - Solange / Lil Wayne
04/22 19:12:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:32 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:28/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:32 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:17/3:55] Mad - Solange / Lil Wayne
04/22 19:12:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:37 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:33/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:37 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:22/3:55] Mad - Solange / Lil Wayne
04/22 19:12:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:38 Info: [stats] 4161mb Virtual, 1921mb Physical, 776mb Managed, 0 Handles, 116 Threads
04/22 19:12:38 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=423216920995 rtt=2000us offset=-339566079us delta=425us drift=1345us in 274.691s (4.898ppm, 17.631ms/hr)
04/22 19:12:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:42 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:38/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:42 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=427364662953 rtt=44000us offset=-339527337us delta=39326us drift=40087us in 278.758s (143.807ppm, 517.704ms/hr)
04/22 19:12:42 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:27/3:55] Mad - Solange / Lil Wayne
04/22 19:12:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:45 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=453510199831 rtt=1000us offset=-88643800us delta=-767us drift=-1898us in 90.601s (-20.954ppm, -75.435ms/hr)
04/22 19:12:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:43/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 1:32/3:55] Mad - Solange / Lil Wayne
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:48 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:48 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:50 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=435411871697 rtt=35000us offset=-339533128us delta=33132us drift=34296us in 286.82s (119.573ppm, 430.464ms/hr)
04/22 19:12:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:52 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:48/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:52 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:37/3:55] Mad - Solange / Lil Wayne
04/22 19:12:52 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=437477839863 rtt=66500us offset=-339502160us delta=30968us drift=65264us in 288.8235s (225.966ppm, 813.477ms/hr)
04/22 19:12:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:53 Info: [stats] 4161mb Virtual, 1921mb Physical, 782mb Managed, 0 Handles, 116 Threads
04/22 19:12:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:57 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:53/6:11] O Estrangeiro - Caetano Veloso
04/22 19:12:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:57 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:42/3:55] Mad - Solange / Lil Wayne
04/22 19:12:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:12:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:02 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 4:58/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:02 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 1:47/3:55] Mad - Solange / Lil Wayne
04/22 19:13:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:05 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":10101,"status":"Dropout"}
04/22 19:13:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:06 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":1269,"status":"Dropout"}
04/22 19:13:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:07 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:03/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:07 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:52/3:55] Mad - Solange / Lil Wayne
04/22 19:13:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:08 Info: [stats] 4161mb Virtual, 1921mb Physical, 785mb Managed, 0 Handles, 116 Threads
04/22 19:13:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:08 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=453556959872 rtt=22500us offset=-339548040us delta=16870us drift=19384us in 304.9925s (63.556ppm, 228.803ms/hr)
04/22 19:13:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:10 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":11787,"status":"Dropout"}
04/22 19:13:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:10 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":3837,"status":"Dropout"}
04/22 19:13:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:11 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":14415,"status":"Dropout"}
04/22 19:13:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:11 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":7347,"status":"Dropout"}
04/22 19:13:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:12 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:08/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:12 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":20571,"status":"Dropout"}
04/22 19:13:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:12 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":22050,"status":"Dropout"}
04/22 19:13:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":22050,"status":"Dropout"}
04/22 19:13:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"samples":22050,"status":"Dropout"}
04/22 19:13:13 Warn: [Receiver HiFi] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
04/22 19:13:13 Trace: [Receiver HiFi] [zoneplayer/raat] too many dropouts. stopping stream
04/22 19:13:13 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:56/3:55] Mad - Solange / Lil Wayne
04/22 19:13:13 Info: [Receiver HiFi] [zoneplayer] advance didn't change the track. returning short read
04/22 19:13:13 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:13:13 Warn: [zone Receiver HiFi] Track Stopped Due to Slow Media
04/22 19:13:13 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:13:13 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Playing => Prepared
04/22 19:13:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [534]{"request":"end_stream"}
04/22 19:13:13 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:13:13 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Inactive
Elements:
------------------------------------------------------------
04/22 19:13:13 Info: [zone Receiver HiFi] OnPlayFeedback StoppedEndOfMediaUnnatural
04/22 19:13:13 Debug: [zone Receiver HiFi] _Advance
04/22 19:13:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [414] {"status":"Ended"}
04/22 19:13:13 Trace: [mlradio] [17] [music/mlradio] GetNextItem(1 / 27)
04/22 19:13:13 Trace: [mlradio] [17] [music/mlradio] returning 1 items for request of 1 items
04/22 19:13:13 Info: [library] recorded play for profile 27272016-553a-4bd6-bf4a-85f335410e2f: mediaid=168:0:65425622 metadataid= contentid=168:0:65425622 libraryid= isfromswim=True
04/22 19:13:13 Trace: [library] finished with 40 dirty tracks 2 dirty albums 4 dirty performers 21 dirty works 21 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 49 changed objects
04/22 19:13:13 Trace: [push] restarting connection (Unable to read data from the transport connection: interrupted.)
04/22 19:13:13 Trace: [push] retrying connection in 76754ms
04/22 19:13:13 Info: sleep 39ms after flush
04/22 19:13:13 Warn: [zoneplayer/raat] Error during streaming: System.NullReferenceException: Object reference not set to an instance of an object
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x005d9] in <4946aaca5e13461cb96ea6a5459ace7a>:0
04/22 19:13:13 Info: [Receiver HiFi] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
04/22 19:13:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [534] {"status":"Success"}
04/22 19:13:14 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Cake & Eat It Too - Bilal
04/22 19:13:14 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
04/22 19:13:14 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"loading","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":239,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":239,"one_line":{"line1":"Cake & Eat It Too - Bilal"},"two_line":{"line1":"Cake & Eat It Too","line2":"Bilal"},"three_line":{"line1":"Cake & Eat It Too","line2":"Bilal","line3":"Airtight's Revenge"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385e49ae169648e5a6cce9bfcded363325ac9241c942e7802b454c20972f59f9830ad55e53cbbdf93f7afbf76a2b7fc7017239dfeef2073205d360d70d9dd3c11b9","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb04dcb6d14018a4691ac9ab56c35ad85332de013366f3dbb060b84059635f5c49b7bca464d930053997e13589cc79bbd1"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":239,"seek_position":null}]}
04/22 19:13:14 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:13:14 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [537]{"request":"update_artwork","mimetype":"image/jpeg"}
04/22 19:13:14 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [537] {"status":"Success"}
04/22 19:13:15 Debug: [music/profilestats] Computed stats in 1368ms, got 50 top albums, 50 top performers, 62 genres
04/22 19:13:16 Debug: [music/profilestats] Computed stats in 1197ms, got 50 top albums, 50 top performers, 62 genres
04/22 19:13:16 Trace: [dbperf] flush 0 bytes, 0 ops in 124 ms (cumulative 407280225 bytes, 297421 ops in 119322 ms)
04/22 19:13:16 Trace: [library] endmutation in 2583ms
04/22 19:13:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/4352082/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:13:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/115812268/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:13:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/4352082?countryCode=DE => Success
04/22 19:13:16 Info: [Receiver HiFi] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMWY2N2NhNDY0YzU0OGE4ODcxMmNkMzk3Y2ZhOGJhNzZfNjEubXA0/0.flac
04/22 19:13:16 Debug: [easyhttp] [8481] GET to https://metadata.roonlabs.net/1/tracks/168:0:4352082/lyrics returned after 322 ms, status code: 404
04/22 19:13:16 Info: [Receiver HiFi] [zoneplayer] Open Result (Playing):Result[Status=Success]
04/22 19:13:16 Info: [Receiver HiFi] [zoneplayer] Starting playback
04/22 19:13:16 Trace: [Receiver HiFi] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) streamid=559276458
04/22 19:13:16 Info: [zone Receiver HiFi] OnPlayFeedback Playing
04/22 19:13:16 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: Bilal - Cake & Eat It Too
04/22 19:13:16 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Cake & Eat It Too - Bilal
04/22 19:13:16 Trace: [streamingmediafile] immediate read of http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInMWY2N2NhNDY0YzU0OGE4ODcxMmNkMzk3Y2ZhOGJhNzZfNjEubXA0/0.flac at 262144 length=27254007
04/22 19:13:16 Trace: [Receiver HiFi] [zoneplayer/raat] synced to endpoint HiFiBerry DAC+ clock: realtime=484471880423 rtt=3000us offset=-88642119us delta=-88642119us
04/22 19:13:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [540]{"request":"stream","stream_id":559276458,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 19:13:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"status":"Buffering"}
04/22 19:13:16 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Buffering
04/22 19:13:16 Trace: [Receiver HiFi] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry DAC+ as clock master
04/22 19:13:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/115812268?countryCode=DE => Success
04/22 19:13:16 Info: [Receiver HiFi] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZGQ4YmFhNGJjYWM5NDI0M2Q2YTk4NDQxYjY4Yjc3OTVfNjEubXA0/0.flac
04/22 19:13:16 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":239,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":239,"one_line":{"line1":"Cake & Eat It Too - Bilal"},"two_line":{"line1":"Cake & Eat It Too","line2":"Bilal"},"three_line":{"line1":"Cake & Eat It Too","line2":"Bilal","line3":"Airtight's Revenge"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385e49ae169648e5a6cce9bfcded363325ac9241c942e7802b454c20972f59f9830ad55e53cbbdf93f7afbf76a2b7fc7017239dfeef2073205d360d70d9dd3c11b9","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb04dcb6d14018a4691ac9ab56c35ad85332de013366f3dbb060b84059635f5c49b7bca464d930053997e13589cc79bbd1"]}}]}
04/22 19:13:17 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/239 sec
04/22 19:13:17 Debug: [raat/tcpaudiosource] connecting to 192.168.178.169:35567
04/22 19:13:17 Debug: [raat/tcpaudiosource] connected
04/22 19:13:17 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"status":"Ready"}
04/22 19:13:17 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Buffering => Ready
04/22 19:13:17 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:13:17 Trace: [Receiver HiFi] [zoneplayer/raat] wait for ready in 0ms
04/22 19:13:17 Trace: [Receiver HiFi] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
04/22 19:13:17 Trace: [Receiver HiFi] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 19:13:17 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [541]{"request":"start","min_offset":59637188,"stream_sample":0}
04/22 19:13:17 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Ready => Playing
04/22 19:13:17 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"status":"Playing"}
04/22 19:13:17 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [541] {"time":485207383334,"status":"Success"}
04/22 19:13:17 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:13:17 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:13/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:22 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:05/3:59] Cake & Eat It Too - Bilal
04/22 19:13:22 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:23 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:18/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:23 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=467870128659 rtt=49500us offset=-339519871us delta=40527us drift=47553us in 319.2505s (148.952ppm, 536.227ms/hr)
04/22 19:13:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:23 Info: [stats] 4161mb Virtual, 1921mb Physical, 814mb Managed, 0 Handles, 115 Threads
04/22 19:13:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:24 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:24 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
04/22 19:13:24 Trace: [push] connecting to 35.243.240.56:9200
04/22 19:13:24 Trace: [push] connected
04/22 19:13:25 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:25 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:25 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:26 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:26 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:26 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:26 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:26 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:27 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:10/3:59] Cake & Eat It Too - Bilal
04/22 19:13:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:28 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:24/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:33 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:29/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:33 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 0:15/3:59] Cake & Eat It Too - Bilal
04/22 19:13:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:33 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:38 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:34/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:38 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:20/3:59] Cake & Eat It Too - Bilal
04/22 19:13:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:38 Info: [stats] 4161mb Virtual, 1921mb Physical, 817mb Managed, 0 Handles, 117 Threads
04/22 19:13:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:39 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=483819638204 rtt=1500us offset=-339566361us delta=-20693us drift=1062us in 335.294s (3.169ppm, 11.408ms/hr)
04/22 19:13:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:41 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":6317,"status":"Dropout"}
04/22 19:13:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:42 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":2008,"status":"Dropout"}
04/22 19:13:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:42 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":22050,"status":"Dropout"}
04/22 19:13:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:39/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":17747,"status":"Dropout"}
04/22 19:13:43 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=488056485030 rtt=48000us offset=-339520514us delta=45333us drift=46909us in 339.439s (138.197ppm, 497.508ms/hr)
04/22 19:13:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:43 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":16954,"status":"Dropout"}
04/22 19:13:43 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:26/3:59] Cake & Eat It Too - Bilal
04/22 19:13:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":4499,"status":"Dropout"}
04/22 19:13:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":12508,"status":"Dropout"}
04/22 19:13:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":6245,"status":"Dropout"}
04/22 19:13:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":19469,"status":"Dropout"}
04/22 19:13:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:46 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":12401,"status":"Dropout"}
04/22 19:13:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:13:46 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"samples":22050,"status":"Dropout"}
04/22 19:13:46 Warn: [Receiver HiFi] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
04/22 19:13:46 Trace: [Receiver HiFi] [zoneplayer/raat] too many dropouts. stopping stream
04/22 19:13:46 Warn: [zone Receiver HiFi] Track Stopped Due to Slow Media
04/22 19:13:46 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Playing => Prepared
04/22 19:13:46 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [571]{"request":"end_stream"}
04/22 19:13:46 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:13:46 Info: [zone Receiver HiFi] OnPlayFeedback StoppedEndOfMediaUnnatural
04/22 19:13:46 Debug: [zone Receiver HiFi] _Advance
04/22 19:13:46 Info: [Receiver HiFi] [zoneplayer] advance didn't change the track. returning short read
04/22 19:13:46 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:13:46 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:13:46 Trace: [mlradio] [17] [music/mlradio] GetNextItem(1 / 26)
04/22 19:13:46 Trace: [mlradio] [17] [music/mlradio] returning 1 items for request of 1 items
04/22 19:13:46 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [540] {"status":"Ended"}
04/22 19:13:46 Info: sleep 39ms after flush
04/22 19:13:46 Warn: [zoneplayer/raat] Error during streaming: System.NullReferenceException: Object reference not set to an instance of an object
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x005d9] in <4946aaca5e13461cb96ea6a5459ace7a>:0
04/22 19:13:46 Info: [Receiver HiFi] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
04/22 19:13:46 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [571] {"status":"Success"}
04/22 19:13:47 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:13:47 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Sinners Prayer - Raphael Saadiq
04/22 19:13:47 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
04/22 19:13:47 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"loading","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":275,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":275,"one_line":{"line1":"Sinners Prayer - Raphael Saadiq"},"two_line":{"line1":"Sinners Prayer","line2":"Raphael Saadiq"},"three_line":{"line1":"Sinners Prayer","line2":"Raphael Saadiq","line3":"Jimmy Lee"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3852f27dabc5478ca3143867e30e603467e4bee3fc11381f997742d176fe2d9ed08d8e5bcbd9b1976a7f4da1216b8da969c3458fe478c8cccd2a4abd5e0d30d0dc0","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb8c49a12c67f5a1e16760c9cdefc8f3a221f9123ddc7e2f69725502ffd8d65bd3c9d5790b35a50f7b0f33e5321e437be2"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":275,"seek_position":null}]}
04/22 19:13:47 Debug: [easyhttp] [8484] GET to https://metadata.roonlabs.net/1/tracks/168:0:115812268/lyrics returned after 149 ms, status code: 200
04/22 19:13:47 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/115812268/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:13:47 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/115812268?countryCode=DE => Success
04/22 19:13:47 Info: [Receiver HiFi] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZGQ4YmFhNGJjYWM5NDI0M2Q2YTk4NDQxYjY4Yjc3OTVfNjEubXA0/0.flac
04/22 19:13:47 Info: [Receiver HiFi] [zoneplayer] Open Result (Playing):Result[Status=Success]
04/22 19:13:47 Info: [Receiver HiFi] [zoneplayer] Starting playback
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) streamid=559276459
04/22 19:13:47 Info: [zone Receiver HiFi] OnPlayFeedback Playing
04/22 19:13:47 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: Raphael Saadiq - Sinners Prayer
04/22 19:13:47 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Sinners Prayer - Raphael Saadiq
04/22 19:13:47 Trace: [streamingmediafile] immediate read of http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZGQ4YmFhNGJjYWM5NDI0M2Q2YTk4NDQxYjY4Yjc3OTVfNjEubXA0/0.flac at 262144 length=29813908
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] synced to endpoint HiFiBerry DAC+ clock: realtime=514925369090 rtt=3000us offset=-88642630us delta=-88642630us
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [576]{"request":"stream","stream_id":559276459,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"status":"Buffering"}
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Buffering
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry DAC+ as clock master
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [577]{"request":"update_artwork","mimetype":"image/jpeg"}
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [577] {"status":"Success"}
04/22 19:13:47 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/59727858/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:13:47 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/59727858?countryCode=DE => Success
04/22 19:13:47 Info: [Receiver HiFi] [zoneplayer] Queueing: http://ab-pr-fa.audio.tidal.com/85872795/bf978acb8d7cc73ce0171b193138d3a4.flac
04/22 19:13:47 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/274 sec
04/22 19:13:47 Debug: [raat/tcpaudiosource] connecting to 192.168.178.169:35567
04/22 19:13:47 Debug: [raat/tcpaudiosource] connected
04/22 19:13:47 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":275,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":275,"one_line":{"line1":"Sinners Prayer - Raphael Saadiq"},"two_line":{"line1":"Sinners Prayer","line2":"Raphael Saadiq"},"three_line":{"line1":"Sinners Prayer","line2":"Raphael Saadiq","line3":"Jimmy Lee"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3852f27dabc5478ca3143867e30e603467e4bee3fc11381f997742d176fe2d9ed08d8e5bcbd9b1976a7f4da1216b8da969c3458fe478c8cccd2a4abd5e0d30d0dc0","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb8c49a12c67f5a1e16760c9cdefc8f3a221f9123ddc7e2f69725502ffd8d65bd3c9d5790b35a50f7b0f33e5321e437be2"]}}],"zones_seek_changed":[{"zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","queue_time_remaining":1998,"seek_position":343}]}
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"status":"Ready"}
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Buffering => Ready
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] wait for ready in 0ms
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [578]{"request":"start","min_offset":59501133,"stream_sample":0}
04/22 19:13:47 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Ready => Playing
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"status":"Playing"}
04/22 19:13:47 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [578] {"time":515311459370,"status":"Success"}
04/22 19:13:47 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:13:48 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:44/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:48 Trace: [streamingmediafile] immediate read of http://ab-pr-fa.audio.tidal.com/85872795/bf978acb8d7cc73ce0171b193138d3a4.flac at 262144 length=40566878
04/22 19:13:48 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:13:49 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=494088145910 rtt=51500us offset=-339517854us delta=43932us drift=49570us in 345.4645s (143.489ppm, 516.559ms/hr)
04/22 19:13:52 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [12% buf] [PLAYING @ 0:05/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:13:53 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:49/6:11] O Estrangeiro - Caetano Veloso
04/22 19:13:53 Info: [stats] 4165mb Virtual, 1921mb Physical, 847mb Managed, 0 Handles, 116 Threads
04/22 19:13:57 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [32% buf] [PLAYING @ 0:10/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:13:58 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 5:54/6:11] O Estrangeiro - Caetano Veloso
04/22 19:14:00 Trace: [prebuffer] short read: 0 / 8820 fill=437440
04/22 19:14:02 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [30% buf] [PLAYING @ 0:15/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:03 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [77% buf] [PLAYING @ 5:59/6:11] O Estrangeiro - Caetano Veloso
04/22 19:14:03 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=508554101992 rtt=169500us offset=-339399898us delta=165326us drift=167526us in 359.6945s (465.746ppm, 1676.686ms/hr)
04/22 19:14:07 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [24% buf] [PLAYING @ 0:20/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:08 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [27% buf] [PLAYING @ 6:04/6:11] O Estrangeiro - Caetano Veloso
04/22 19:14:08 Info: [stats] 4165mb Virtual, 1921mb Physical, 852mb Managed, 0 Handles, 115 Threads
04/22 19:14:09 Trace: [CalDigit] [zoneplayer/raat] sync CalDigit USB-C Pro Audio: realtime=514153563905 rtt=2000us offset=-339565436us delta=647us drift=1988us in 365.627s (5.438ppm, 19.576ms/hr)
04/22 19:14:09 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/158 sec
04/22 19:14:12 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [22% buf] [PLAYING @ 0:25/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:13 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [7% buf] [PLAYING @ 6:09/6:11] O Estrangeiro - Caetano Veloso
04/22 19:14:15 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' DONE: Caetano Veloso - O Estrangeiro
04/22 19:14:15 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 BitRate=824 Quality=Lossless
Raat Device=CalDigit USB-C Pro Audio
Output OutputType=Local_SharedMode_CoreAudio Quality=HighQuality SubType= Model=CalDigit USB-C Pro Audio
------------------------------------------------------------
04/22 19:14:15 Info: [zone CalDigit] OnPlayFeedback OnToNext
04/22 19:14:15 Debug: [zone CalDigit] _Advance
04/22 19:14:15 Info: [library] recorded play for profile 27272016-553a-4bd6-bf4a-85f335410e2f: mediaid=50:1:ffee8b9f-91b1-49a6-a60d-c93fe4da6b1e metadataid=123:0:MT0033168370 contentid= libraryid=50:1:ffee8b9f-91b1-49a6-a60d-c93fe4da6b1e isfromswim=False
04/22 19:14:15 Trace: [library] finished with 784 dirty tracks 71 dirty albums 113 dirty performers 208 dirty works 308 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 680 changed objects
04/22 19:14:15 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: Caetano Veloso - Rai das Cores (Rai of Colors)
04/22 19:14:15 Info: [zone CalDigit] OnPlayFeedback Playing
04/22 19:14:15 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","display_name":"CalDigit","outputs":[{"output_id":"1701cf4fc4963e60032527cbf65e7d1d32dc","zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","can_group_with_output_ids":[],"display_name":"CalDigit","volume":{"type":"number","min":0,"max":100,"value":100,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"CalDigit USB-C Pro Audio","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":9,"queue_time_remaining":1970,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":0,"length":158,"one_line":{"line1":"Rai das Cores (Rai of Colors) - Caetano Veloso"},"two_line":{"line1":"Rai das Cores (Rai of Colors)","line2":"Caetano Veloso"},"three_line":{"line1":"Rai das Cores (Rai of Colors)","line2":"Caetano Veloso","line3":"Estrangeiro"},"image_key":"51eaea9dfeb28ab4bb383a484657d017","artist_image_keys":["7ec5ef6ee8ed7db758ae2dc06223c9ac"]}}],"zones_seek_changed":[{"zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","queue_time_remaining":1970,"seek_position":0}]}
04/22 19:14:16 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
Source Format=Flac 44100/16/2 BitRate=824 Quality=Lossless
Raat Device=CalDigit USB-C Pro Audio
Output OutputType=Local_SharedMode_CoreAudio Quality=HighQuality SubType= Model=CalDigit USB-C Pro Audio
------------------------------------------------------------
04/22 19:14:16 Debug: [music/profilestats] Computed stats in 1284ms, got 50 top albums, 50 top performers, 62 genres
04/22 19:14:17 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=545255270145 rtt=1500us offset=-88643729us delta=569us drift=-1098us in 30.3335s (-36.229ppm, -130.424ms/hr)
04/22 19:14:17 Warn: [CalDigit] [zoneplayer/raat] long rtt sync CalDigit USB-C Pro Audio: realtime=522400712079 rtt=53500us offset=-339515287us delta=44870us drift=52136us in 373.7725s (139.487ppm, 502.153ms/hr)
04/22 19:14:18 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [12% buf] [PLAYING @ 0:30/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:18 Debug: [music/profilestats] Computed stats in 1236ms, got 50 top albums, 50 top performers, 62 genres
04/22 19:14:18 Trace: [dbperf] flush 0 bytes, 0 ops in 122 ms (cumulative 407280225 bytes, 297421 ops in 119444 ms)
04/22 19:14:18 Trace: [library] endmutation in 2698ms
04/22 19:14:18 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PLAYING @ 0:02/2:38] Rai das Cores (Rai of Colors) - Caetano Veloso
04/22 19:14:18 Info: [CalDigit] [zoneplayer] Queueing: /var/packages/RoonServer/target/roonmnt/music/Caetano Veloso/(1989) Estrangeiro [4016310]/03 - Branquinha.flac
04/22 19:14:18 Info: [CalDigit] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:14:18 Debug: [easyhttp] [8487] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-de returned after 231 ms, status code: 200
04/22 19:14:18 Debug: [easyhttp] [8489] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-de returned after 302 ms, status code: 200
04/22 19:14:18 Debug: [easyhttp] [8490] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-de returned after 213 ms, status code: 200
04/22 19:14:18 Debug: [easyhttp] [8488] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-de returned after 311 ms, status code: 200
04/22 19:14:20 Trace: [zone CalDigit] PlayPause
04/22 19:14:20 Trace: [zone CalDigit] Pause
04/22 19:14:20 Info: [zone CalDigit] Canceling Pending Sleep
04/22 19:14:20 Trace: [CalDigit] [zoneplayer/raat] Pausing at streamtime_ns 376553611747 now_ns 525179945573
04/22 19:14:20 Trace: [CalDigit USB-C Pro Audio] [raatclient] SENT [22]{"request":"stop"}
04/22 19:14:20 Trace: [CalDigit] [zoneplayer/raat] Endpoint CalDigit USB-C Pro Audio State Changed: Playing => Paused
04/22 19:14:20 Info: [zone CalDigit] OnPlayFeedback Paused
04/22 19:14:20 Trace: [CalDigit] [HighQuality, 16/44 FLAC => 16/44] [100% buf] [PAUSED @ 0:05/2:38] Rai das Cores (Rai of Colors) - Caetano Veloso
04/22 19:14:20 Trace: [CalDigit USB-C Pro Audio] [raatclient] GOT [20] {"status":"Stopped"}
04/22 19:14:20 Trace: [CalDigit USB-C Pro Audio] [raatclient] GOT [22] {"status":"Success"}
04/22 19:14:20 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","display_name":"CalDigit","outputs":[{"output_id":"1701cf4fc4963e60032527cbf65e7d1d32dc","zone_id":"1601cf4fc4963e60032527cbf65e7d1d32dc","can_group_with_output_ids":[],"display_name":"CalDigit","volume":{"type":"number","min":0,"max":100,"value":100,"step":1,"is_muted":false,"hard_limit_min":0,"hard_limit_max":100,"soft_limit":100},"source_controls":[{"control_key":"1","display_name":"CalDigit USB-C Pro Audio","supports_standby":false,"status":"indeterminate"}]}],"state":"paused","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":false,"is_play_allowed":true,"is_seek_allowed":true,"queue_items_remaining":9,"queue_time_remaining":1965,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":5,"length":158,"one_line":{"line1":"Rai das Cores (Rai of Colors) - Caetano Veloso"},"two_line":{"line1":"Rai das Cores (Rai of Colors)","line2":"Caetano Veloso"},"three_line":{"line1":"Rai das Cores (Rai of Colors)","line2":"Caetano Veloso","line3":"Estrangeiro"},"image_key":"51eaea9dfeb28ab4bb383a484657d017","artist_image_keys":["7ec5ef6ee8ed7db758ae2dc06223c9ac"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":242,"seek_position":33}]}
04/22 19:14:23 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [6% buf] [PLAYING @ 0:35/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:23 Info: [stats] 4165mb Virtual, 1923mb Physical, 870mb Managed, 0 Handles, 116 Threads
04/22 19:14:25 Trace: [zone CalDigit] [zone] no playback for 5s, suspending to release audio device
04/22 19:14:25 Trace: [zone CalDigit] Suspend
04/22 19:14:25 Trace: [zone CalDigit] Stop
04/22 19:14:25 Info: [zone CalDigit] OnPlayFeedback Stopped
04/22 19:14:25 Info: [zone CalDigit] Canceling Pending Sleep
04/22 19:14:25 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:14:25 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:14:25 Trace: [CalDigit] [zoneplayer/raat] Endpoint CalDigit USB-C Pro Audio State Changed: Paused => Prepared
04/22 19:14:25 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:14:25 Trace: [CalDigit USB-C Pro Audio] [raatclient] SENT [23]{"request":"end_stream"}
04/22 19:14:25 Trace: [CalDigit USB-C Pro Audio] [raatclient] SENT [24]{"request":"teardown"}
04/22 19:14:25 Trace: [CalDigit] [zoneplayer/raat] Endpoint CalDigit USB-C Pro Audio State Changed: Prepared => Idle
04/22 19:14:25 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:14:25 Info: sleep 3ms after flush
04/22 19:14:25 Trace: [CalDigit USB-C Pro Audio] [raatclient] GOT [20] {"status":"Ended"}
04/22 19:14:25 Trace: [CalDigit USB-C Pro Audio] [raatclient] GOT [23] {"status":"Success"}
04/22 19:14:25 Trace: [CalDigit USB-C Pro Audio] [raatclient] GOT [19] {"status":"Teardown"}
04/22 19:14:28 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [12% buf] [PLAYING @ 0:40/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:33 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [6% buf] [PLAYING @ 0:46/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:38 Info: [stats] 4165mb Virtual, 1923mb Physical, 876mb Managed, 0 Handles, 114 Threads
04/22 19:14:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:38 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:51/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:43 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:56/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:45 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:46 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:47 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:48 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:48 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:48 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:01/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:49 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:50 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:51 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=578952955386 rtt=2500us offset=-88642044us delta=644us drift=586us in 64.0285s (9.157ppm, 32.964ms/hr)
04/22 19:14:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:53 Info: [stats] 4165mb Virtual, 1923mb Physical, 881mb Managed, 0 Handles, 114 Threads
04/22 19:14:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:53 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 1:06/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:58 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:11/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:14:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:14:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:03 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:16/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:08 Info: [stats] 4165mb Virtual, 1923mb Physical, 881mb Managed, 0 Handles, 114 Threads
04/22 19:15:08 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:21/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:15:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:10 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:13 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:13 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 1:26/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:15:14 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:14 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:14 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:16 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:16 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:16 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:17 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:17 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:17 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:17 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:17 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:18 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:18 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:18 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:18 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:31/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:15:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:19 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:20 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:21 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:21 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":4901,"status":"Dropout"}
04/22 19:15:21 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:21 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":15958,"status":"Dropout"}
04/22 19:15:22 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:22 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":21641,"status":"Dropout"}
04/22 19:15:22 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:22 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":22050,"status":"Dropout"}
04/22 19:15:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:23 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":22050,"status":"Dropout"}
04/22 19:15:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:23 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:23 Info: [stats] 4165mb Virtual, 1923mb Physical, 890mb Managed, 0 Handles, 114 Threads
04/22 19:15:23 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":22050,"status":"Dropout"}
04/22 19:15:24 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 1:35/4:35] Sinners Prayer - Raphael Saadiq
04/22 19:15:24 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:24 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:24 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=611860191015 rtt=1000us offset=-88642808us delta=373us drift=-178us in 96.938s (-1.837ppm, -6.613ms/hr)
04/22 19:15:24 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":22050,"status":"Dropout"}
04/22 19:15:24 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:24 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:24 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"samples":22050,"status":"Dropout"}
04/22 19:15:24 Warn: [Receiver HiFi] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
04/22 19:15:24 Trace: [Receiver HiFi] [zoneplayer/raat] too many dropouts. stopping stream
04/22 19:15:24 Warn: [zone Receiver HiFi] Track Stopped Due to Slow Media
04/22 19:15:24 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Playing => Prepared
04/22 19:15:24 Info: [Receiver HiFi] [zoneplayer] advance didn't change the track. returning short read
04/22 19:15:24 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [676]{"request":"end_stream"}
04/22 19:15:24 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:15:24 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:15:24 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:15:24 Info: [library] recorded play for profile 27272016-553a-4bd6-bf4a-85f335410e2f: mediaid=168:0:115812268 metadataid= contentid=168:0:115812268 libraryid= isfromswim=True
04/22 19:15:24 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Inactive
Elements:
------------------------------------------------------------
04/22 19:15:24 Info: [zone Receiver HiFi] OnPlayFeedback StoppedEndOfMediaUnnatural
04/22 19:15:24 Debug: [zone Receiver HiFi] _Advance
04/22 19:15:24 Trace: [mlradio] [17] [music/mlradio] GetNextItem(1 / 25)
04/22 19:15:24 Trace: [mlradio] [17] [music/mlradio] returning 1 items for request of 1 items
04/22 19:15:24 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [576] {"status":"Ended"}
04/22 19:15:25 Info: sleep 39ms after flush
04/22 19:15:25 Warn: [zoneplayer/raat] Error during streaming: System.NullReferenceException: Object reference not set to an instance of an object
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x005d9] in <4946aaca5e13461cb96ea6a5459ace7a>:0
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [676] {"status":"Success"}
04/22 19:15:25 Info: [Receiver HiFi] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
04/22 19:15:25 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:15:25 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] Hold Up - Beyoncé
04/22 19:15:25 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
04/22 19:15:25 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"loading","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":221,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":221,"one_line":{"line1":"Hold Up - Beyonc\u00e9"},"two_line":{"line1":"Hold Up","line2":"Beyonc\u00e9"},"three_line":{"line1":"Hold Up","line2":"Beyonc\u00e9","line3":"LEMONADE"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385cd3bed24111a105d690e3bc9a2e187e11bd61470af41fcabe633e7b486e741d50cfb22c6b35a5ea9f01d93ba95bbdbf1bdbb165d6d9138ba43e343d85843fc0d","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb035f1900736bd043948d2976e015b1f5f8e3759a6a26599df355a6f776c6d4a82aef86d2416eaa58a3315f8734944871"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":221,"seek_position":null}]}
04/22 19:15:25 Debug: [easyhttp] [8492] GET to https://metadata.roonlabs.net/1/tracks/168:0:59727858/lyrics returned after 129 ms, status code: 200
04/22 19:15:25 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/59727858/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:15:25 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/59727858?countryCode=DE => Success
04/22 19:15:25 Info: [Receiver HiFi] [zoneplayer] Playing: http://ab-pr-fa.audio.tidal.com/85872795/bf978acb8d7cc73ce0171b193138d3a4.flac
04/22 19:15:25 Trace: [streamingmediafile] immediate read of http://ab-pr-fa.audio.tidal.com/85872795/bf978acb8d7cc73ce0171b193138d3a4.flac at 262144 length=40566878
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [679]{"request":"update_artwork","mimetype":"image/jpeg"}
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [679] {"status":"Success"}
04/22 19:15:25 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/81726117/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:15:25 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/81726117?countryCode=DE => Success
04/22 19:15:25 Info: [Receiver HiFi] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZWRhMGM4YmRhN2VkYjM3ZThlYzNhZThiY2RiMjFiNzVfNjEubXA0/0.flac
04/22 19:15:25 Info: [Receiver HiFi] [zoneplayer] Open Result (Playing):Result[Status=Success]
04/22 19:15:25 Info: [Receiver HiFi] [zoneplayer] Starting playback
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=88200, isdts=False, mqa_core=44100) => StreamFormat(channels=2, bitspersample=24, samplerate=88200, isdts=False, mqa_core=44100) streamid=559276460
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [681]{"request":"teardown"}
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Idle
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [682]{"request":"setup","format":{"sample_type":"pcm","sample_rate":88200,"bits_per_sample":24,"channels":2,"sample_subtype":"mqa_core","mqa_original_sample_rate":44100}}
04/22 19:15:25 Info: [zone Receiver HiFi] OnPlayFeedback Playing
04/22 19:15:25 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: Beyoncé - Hold Up
04/22 19:15:25 Trace: [Receiver HiFi] [Lossless, 24/44 MQA TIDAL FLAC => 24/88 MQA] [PLAYING @ 0:00] Hold Up - Beyoncé
04/22 19:15:25 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":221,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":221,"one_line":{"line1":"Hold Up - Beyonc\u00e9"},"two_line":{"line1":"Hold Up","line2":"Beyonc\u00e9"},"three_line":{"line1":"Hold Up","line2":"Beyonc\u00e9","line3":"LEMONADE"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b385cd3bed24111a105d690e3bc9a2e187e11bd61470af41fcabe633e7b486e741d50cfb22c6b35a5ea9f01d93ba95bbdbf1bdbb165d6d9138ba43e343d85843fc0d","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fb035f1900736bd043948d2976e015b1f5f8e3759a6a26599df355a6f776c6d4a82aef86d2416eaa58a3315f8734944871"]}}]}
04/22 19:15:25 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/221 sec
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [12] {"status":"Teardown"}
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [682] {"message":{"signal_path":[{"method":"analog","type":"output","quality":"lossless"}]},"status":"OutputMessage"}
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [682] {"audio_port":0,"status":"Success","audio_port_tcp":35567,"clock_port":56602}
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Idle => Prepared
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] synced to endpoint HiFiBerry DAC+ clock: realtime=306714860665 rtt=1000us offset=-395528139us delta=-395528139us
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [684]{"request":"stream","stream_id":559276460,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 19:15:25 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"status":"Buffering"}
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Buffering
04/22 19:15:25 Trace: [Receiver HiFi] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry DAC+ as clock master
04/22 19:15:26 Debug: [raat/tcpaudiosource] connecting to 192.168.178.169:35567
04/22 19:15:26 Debug: [raat/tcpaudiosource] connected
04/22 19:15:26 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/24/2 Quality=Lossless MQA 44100
MQA Subtype=authentication LightState=off OriginalSampleRate=44100 OutputSampleRate= OutputBitsPerSample=
MQA Subtype=roon_core_decoder LightState= OriginalSampleRate= OutputSampleRate=88200 OutputBitsPerSample=24
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:15:26 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"status":"Ready"}
04/22 19:15:26 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Buffering => Ready
04/22 19:15:26 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:15:26 Trace: [Receiver HiFi] [zoneplayer/raat] wait for ready in 0ms
04/22 19:15:26 Trace: [Receiver HiFi] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
04/22 19:15:26 Trace: [Receiver HiFi] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 19:15:26 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [685]{"request":"start","min_offset":59682539,"stream_sample":0}
04/22 19:15:26 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Ready => Playing
04/22 19:15:26 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"status":"Playing"}
04/22 19:15:26 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [685] {"time":307696346852,"status":"Success"}
04/22 19:15:27 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/24/2 Quality=Lossless MQA 44100
MQA Subtype=authentication LightState=authored OriginalSampleRate=44100 OutputSampleRate= OutputBitsPerSample=
MQA Subtype=roon_core_decoder LightState= OriginalSampleRate= OutputSampleRate=88200 OutputBitsPerSample=24
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:15:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:27 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:28 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:29 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:30 Trace: [Receiver HiFi] [Lossless 29.5x, 24/44 MQA TIDAL FLAC => 24/88 MQA] [2% buf] [PLAYING @ 0:03/3:41] Hold Up - Beyoncé
04/22 19:15:30 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:31 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:32 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:34 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:35 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:36 Trace: [Receiver HiFi] [Lossless 29.7x, 24/44 MQA TIDAL FLAC => 24/88 MQA] [PLAYING @ 0:09/3:41] Hold Up - Beyoncé
04/22 19:15:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:36 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:37 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:38 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:38 Info: [stats] 4165mb Virtual, 1925mb Physical, 914mb Managed, 0 Handles, 114 Threads
04/22 19:15:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:39 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:40 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:41 Trace: [Receiver HiFi] [Lossless 29.6x, 24/44 MQA TIDAL FLAC => 24/88 MQA] [2% buf] [PLAYING @ 0:14/3:41] Hold Up - Beyoncé
04/22 19:15:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:41 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":16279,"status":"Dropout"}
04/22 19:15:41 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:42 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:42 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:42 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:43 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:43 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:43 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:44 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"samples":44100,"status":"Dropout"}
04/22 19:15:44 Warn: [Receiver HiFi] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
04/22 19:15:44 Trace: [Receiver HiFi] [zoneplayer/raat] too many dropouts. stopping stream
04/22 19:15:44 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Playing => Prepared
04/22 19:15:44 Warn: [zone Receiver HiFi] Track Stopped Due to Slow Media
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [703]{"request":"end_stream"}
04/22 19:15:44 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:15:44 Info: [zone Receiver HiFi] OnPlayFeedback StoppedEndOfMediaUnnatural
04/22 19:15:44 Debug: [zone Receiver HiFi] _Advance
04/22 19:15:44 Trace: [mlradio] [17] [music/mlradio] GetNextItem(1 / 24)
04/22 19:15:44 Trace: [mlradio] [17] [music/mlradio] returning 1 items for request of 1 items
04/22 19:15:44 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:15:44 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [684] {"status":"Ended"}
04/22 19:15:44 Info: sleep 39ms after flush
04/22 19:15:44 Info: [Receiver HiFi] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [703] {"status":"Success"}
04/22 19:15:44 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:15:44 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/81726117/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:15:44 Trace: [Receiver HiFi] [Lossless, 24/44 MQA TIDAL FLAC => 24/88 MQA] [LOADING @ 0:00] Banana Clip - Miguel
04/22 19:15:44 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
04/22 19:15:44 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/81726117?countryCode=DE => Success
04/22 19:15:44 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/56857867/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:15:44 Info: [Receiver HiFi] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZWRhMGM4YmRhN2VkYjM3ZThlYzNhZThiY2RiMjFiNzVfNjEubXA0/0.flac
04/22 19:15:44 Info: [Receiver HiFi] [zoneplayer] Open Result (Playing):Result[Status=Success]
04/22 19:15:44 Info: [Receiver HiFi] [zoneplayer] Starting playback
04/22 19:15:44 Trace: [Receiver HiFi] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) streamid=559276461
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [707]{"request":"teardown"}
04/22 19:15:44 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Idle
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [708]{"request":"setup","format":{"sample_type":"pcm","sample_rate":44100,"bits_per_sample":16,"channels":2,"sample_subtype":"none"}}
04/22 19:15:44 Info: [zone Receiver HiFi] OnPlayFeedback Playing
04/22 19:15:44 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: Miguel - Banana Clip
04/22 19:15:44 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] Banana Clip - Miguel
04/22 19:15:44 Trace: [streamingmediafile] immediate read of http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInZWRhMGM4YmRhN2VkYjM3ZThlYzNhZThiY2RiMjFiNzVfNjEubXA0/0.flac at 262144 length=23239137
04/22 19:15:44 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":201,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":201,"one_line":{"line1":"Banana Clip - Miguel"},"two_line":{"line1":"Banana Clip","line2":"Miguel"},"three_line":{"line1":"Banana Clip","line2":"Miguel","line3":"War & Leisure"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b3854396b70becaec61d6020bb28f110a9e7e98d0db4486c2dfac33e41910979e4313a3d538e2a8f3afbeb2108a8fdb9ea7632f99b4ec9cc77843a2988c5610f9ad4","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbe8e0550bd8a725c34a380fb056689f0abf1d0260aa0d9b0bb50b1ea8ef2ea24b6eeaaf5c0658443f6472154ce0ea3603"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":201,"seek_position":null}]}
04/22 19:15:44 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/56857867?countryCode=DE => Success
04/22 19:15:44 Info: [Receiver HiFi] [zoneplayer] Queueing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInNTBiYjU3MDMxOTEyNmYzNWFmMmUzZjk5ZDVjOTQ1YzhfNjEubXA0/0.flac
04/22 19:15:44 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [682] {"status":"Teardown"}
04/22 19:15:45 Debug: [easyhttp] [8496] GET to https://metadata.roonlabs.net/1/tracks/168:0:81726117/lyrics returned after 129 ms, status code: 200
04/22 19:15:45 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/201 sec
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [708] {"message":{"signal_path":[{"method":"analog","type":"output","quality":"lossless"}]},"status":"OutputMessage"}
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [708] {"audio_port":0,"status":"Success","audio_port_tcp":35567,"clock_port":56602}
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Idle => Prepared
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] synced to endpoint HiFiBerry DAC+ clock: realtime=651355347793 rtt=1000us offset=-70133652us delta=-70133652us
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [710]{"request":"stream","stream_id":559276461,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"status":"Buffering"}
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Buffering
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry DAC+ as clock master
04/22 19:15:45 Debug: [raat/tcpaudiosource] connecting to 192.168.178.169:35567
04/22 19:15:45 Debug: [raat/tcpaudiosource] connected
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [711]{"request":"update_artwork","mimetype":"image/jpeg"}
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [711] {"status":"Success"}
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"status":"Ready"}
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Buffering => Ready
04/22 19:15:45 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] wait for ready in 0ms
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [712]{"request":"start","min_offset":59637188,"stream_sample":0}
04/22 19:15:45 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Ready => Playing
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"status":"Playing"}
04/22 19:15:45 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [712] {"time":651824937518,"status":"Success"}
04/22 19:15:47 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:15:49 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [10% buf] [PLAYING @ 0:04/3:21] Banana Clip - Miguel
04/22 19:15:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:51 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:52 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:53 Info: [stats] 4166mb Virtual, 1926mb Physical, 940mb Managed, 0 Handles, 114 Threads
04/22 19:15:53 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:54 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:55 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [2% buf] [PLAYING @ 0:09/3:21] Banana Clip - Miguel
04/22 19:15:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:55 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:56 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:57 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:58 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:15:59 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:00 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:14/3:21] Banana Clip - Miguel
04/22 19:16:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:00 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:01 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:02 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:03 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:04 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:05 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:19/3:21] Banana Clip - Miguel
04/22 19:16:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:05 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:06 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:07 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:08 Trace: [broker/accounts] [heartbeat] now=4/22/2021 5:16:08 PM nextauthrefresh=4/22/2021 5:26:09 PM nextmachineallocate=4/22/2021 7:21:08 PM
04/22 19:16:08 Info: [stats] 4166mb Virtual, 1926mb Physical, 940mb Managed, 0 Handles, 114 Threads
04/22 19:16:08 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:09 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:10 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:25/3:21] Banana Clip - Miguel
04/22 19:16:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:11 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:12 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:12 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":9893,"status":"Dropout"}
04/22 19:16:13 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:13 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:13 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:14 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:14 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:14 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:14 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:15 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:15 Debug: [prebuffer] sleeping in read -- this isn't good
04/22 19:16:15 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"samples":22050,"status":"Dropout"}
04/22 19:16:15 Warn: [Receiver HiFi] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
04/22 19:16:15 Trace: [Receiver HiFi] [zoneplayer/raat] too many dropouts. stopping stream
04/22 19:16:15 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Playing => Prepared
04/22 19:16:15 Warn: [zone Receiver HiFi] Track Stopped Due to Slow Media
04/22 19:16:15 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [742]{"request":"end_stream"}
04/22 19:16:15 Debug: [raat/tcpaudiosource] disconnecting
04/22 19:16:15 Info: [Receiver HiFi] [zoneplayer] advance didn't change the track. returning short read
04/22 19:16:15 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 19:16:15 Info: [zone Receiver HiFi] OnPlayFeedback StoppedEndOfMediaUnnatural
04/22 19:16:15 Debug: [zone Receiver HiFi] _Advance
04/22 19:16:15 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 19:16:15 Trace: [mlradio] [17] [music/mlradio] GetNextItem(1 / 23)
04/22 19:16:15 Trace: [mlradio] [17] [music/mlradio] returning 1 items for request of 1 items
04/22 19:16:15 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [710] {"status":"Ended"}
04/22 19:16:15 Info: sleep 39ms after flush
04/22 19:16:15 Warn: [zoneplayer/raat] Error during streaming: System.NullReferenceException: Object reference not set to an instance of an object
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x005d9] in <4946aaca5e13461cb96ea6a5459ace7a>:0
04/22 19:16:15 Info: [Receiver HiFi] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
04/22 19:16:15 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [742] {"status":"Success"}
04/22 19:16:15 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [LOADING @ 0:00] In the Meantime - We Are King
04/22 19:16:15 Info: ComputeSwimType(LimitToSeed=False, PerformerIds.Count=0, ComposerIds.Count=0)
04/22 19:16:15 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"loading","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":false,"queue_items_remaining":1,"queue_time_remaining":276,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":null,"length":276,"one_line":{"line1":"In the Meantime - We Are King"},"two_line":{"line1":"In the Meantime","line2":"We Are King"},"three_line":{"line1":"In the Meantime","line2":"We Are King","line3":"We Are KING"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b38589eb497c6e13d94e12e9ed2e5f5e5422364a9e93e6e3a5824219f4f9033a3198e52883594003fde65fc347a06f34025af5024b23c6026ec89e6d571048bf3366","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbc01f7abb2ec13f0b10a33bf82d5a8b634e00fbcf8494d03aba9cbb7db9c8cffd728486010cacc3b35208eda46436119d"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":276,"seek_position":null}]}
04/22 19:16:16 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 19:16:16 Debug: [easyhttp] [8498] GET to https://metadata.roonlabs.net/1/tracks/168:0:56857867/lyrics returned after 128 ms, status code: 200
04/22 19:16:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/56857867/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:16:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/58597136/playbackinfopostpaywall?countryCode=DE&audioquality=HI_RES&assetpresentation=FULL&playbackmode=STREAM => Success
04/22 19:16:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/56857867?countryCode=DE => Success
04/22 19:16:16 Info: [Receiver HiFi] [zoneplayer] Playing: http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInNTBiYjU3MDMxOTEyNmYzNWFmMmUzZjk5ZDVjOTQ1YzhfNjEubXA0/0.flac
04/22 19:16:16 Info: [Receiver HiFi] [zoneplayer] Open Result (Playing):Result[Status=Success]
04/22 19:16:16 Info: [Receiver HiFi] [zoneplayer] Starting playback
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=44100, isdts=False) streamid=559276462
04/22 19:16:16 Info: [zone Receiver HiFi] OnPlayFeedback Playing
04/22 19:16:16 Debug: Lastfm 'xvNVhwi380xJ3Vsrl7NFHf1_gsIbgvqB' START: King - In the Meantime
04/22 19:16:16 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [PLAYING @ 0:00] In the Meantime - We Are King
04/22 19:16:16 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/276 sec
04/22 19:16:16 Trace: [streamingmediafile] immediate read of http://sp-pr-fa.audio.tidal.com/mediatracks/CAEaKRInNTBiYjU3MDMxOTEyNmYzNWFmMmUzZjk5ZDVjOTQ1YzhfNjEubXA0/0.flac at 262144 length=27240772
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] synced to endpoint HiFiBerry DAC+ clock: realtime=682328124830 rtt=3000us offset=-70131875us delta=-70131875us
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [747]{"request":"stream","stream_id":559276462,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [747] {"status":"Buffering"}
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Prepared => Buffering
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] [zoneplayer/raat] selected HiFiBerry DAC+ as clock master
04/22 19:16:16 Debug: [raat/tcpaudiosource] connecting to 192.168.178.169:35567
04/22 19:16:16 Debug: [raat/tcpaudiosource] connected
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [747] {"status":"Ready"}
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Buffering => Ready
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] wait for ready in 70ms
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] Adjusting playback start offset from 50ms to 59ms
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [748]{"request":"start","min_offset":59501133,"stream_sample":0}
04/22 19:16:16 Trace: [Receiver HiFi] [zoneplayer/raat] Endpoint HiFiBerry DAC+ State Changed: Ready => Playing
04/22 19:16:16 Trace: [tidal/http] GET https://api.tidal.com/v1/tracks/58597136?countryCode=DE => Success
04/22 19:16:16 Info: [Receiver HiFi] [zoneplayer] Queueing: http://ab-pr-fa.audio.tidal.com/40293ad0a41445185a694e45e2f346da_39.flac
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [747] {"status":"Playing"}
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [748] {"time":682484405278,"status":"Success"}
04/22 19:16:16 Trace: [roonapi] [apiclient 192.168.178.167:40640] CONTINUE Changed {"zones_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","display_name":"Receiver HiFi","outputs":[{"output_id":"170193aaca83a5d429459931b5add8b8e672","zone_id":"160193aaca83a5d429459931b5add8b8e672","can_group_with_output_ids":["170193aaca83a5d429459931b5add8b8e672"],"display_name":"Receiver HiFi","volume":{"type":"db","min":-100,"max":0,"value":0,"step":0.5,"is_muted":false,"hard_limit_min":-100,"hard_limit_max":0,"soft_limit":0},"source_controls":[{"control_key":"1","display_name":"HiFiBerry DAC+","supports_standby":false,"status":"indeterminate"}]}],"state":"playing","is_next_allowed":true,"is_previous_allowed":true,"is_pause_allowed":true,"is_play_allowed":false,"is_seek_allowed":true,"queue_items_remaining":1,"queue_time_remaining":276,"settings":{"loop":"disabled","shuffle":false,"auto_radio":true},"now_playing":{"seek_position":0,"length":276,"one_line":{"line1":"In the Meantime - We Are King"},"two_line":{"line1":"In the Meantime","line2":"We Are King"},"three_line":{"line1":"In the Meantime","line2":"We Are King","line3":"We Are KING"},"image_key":"4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4728dce89c57a67c6fd1c4aed54461b38589eb497c6e13d94e12e9ed2e5f5e5422364a9e93e6e3a5824219f4f9033a3198e52883594003fde65fc347a06f34025af5024b23c6026ec89e6d571048bf3366","artist_image_keys":["4e58d73e0e90052294a1425dbcbc939078ed042d692b685c5497ac5628be8b4794fab8f5b705d380d5942fd6b87d02d19623299361ca3e2313368d44331d36fbc01f7abb2ec13f0b10a33bf82d5a8b634e00fbcf8494d03aba9cbb7db9c8cffd728486010cacc3b35208eda46436119d"]}}],"zones_seek_changed":[{"zone_id":"160193aaca83a5d429459931b5add8b8e672","queue_time_remaining":276,"seek_position":0}]}
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] SENT [750]{"request":"update_artwork","mimetype":"image/jpeg"}
04/22 19:16:16 Trace: [HiFiBerry DAC+ @ 192.168.178.169:44503] [raatclient] GOT [750] {"status":"Success"}
04/22 19:16:16 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Lossless
Elements:
Source Format=Flac 44100/16/2 Quality=Lossless
Raat Device=HiFiBerry DAC+
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
04/22 19:16:17 Info: [Receiver HiFi] [zoneplayer] Open result (Queueing): Result[Status=Success]
04/22 19:16:21 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [64% buf] [PLAYING @ 0:04/4:36] In the Meantime - We Are King
04/22 19:16:23 Info: [stats] 4184mb Virtual, 1943mb Physical, 969mb Managed, 0 Handles, 114 Threads
04/22 19:16:26 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [74% buf] [PLAYING @ 0:10/4:36] In the Meantime - We Are King
04/22 19:16:31 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [92% buf] [PLAYING @ 0:15/4:36] In the Meantime - We Are King
04/22 19:16:36 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:20/4:36] In the Meantime - We Are King
04/22 19:16:38 Info: [stats] 4185mb Virtual, 1944mb Physical, 971mb Managed, 0 Handles, 114 Threads
04/22 19:16:41 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:25/4:36] In the Meantime - We Are King
04/22 19:16:46 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=712583355664 rtt=1000us offset=-70133644us delta=-98us drift=-1769us in 30.259s (-58.467ppm, -210.483ms/hr)
04/22 19:16:46 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:30/4:36] In the Meantime - We Are King
04/22 19:16:51 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:35/4:36] In the Meantime - We Are King
04/22 19:16:53 Info: [stats] 4172mb Virtual, 1938mb Physical, 676mb Managed, 0 Handles, 114 Threads
04/22 19:16:56 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:40/4:36] In the Meantime - We Are King
04/22 19:17:01 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:45/4:36] In the Meantime - We Are King
04/22 19:17:06 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:50/4:36] In the Meantime - We Are King
04/22 19:17:08 Info: [stats] 4172mb Virtual, 1938mb Physical, 677mb Managed, 0 Handles, 114 Threads
04/22 19:17:12 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:55/4:36] In the Meantime - We Are King
04/22 19:17:16 Trace: [Receiver HiFi] [zoneplayer/raat] sync HiFiBerry DAC+: realtime=742813334219 rtt=500us offset=-70133665us delta=303us drift=-1790us in 60.4885s (-29.603ppm, -106.569ms/hr)
04/22 19:17:17 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 1:00/4:36] In the Meantime - We Are King
04/22 19:17:22 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 1:05/4:36] In the Meantime - We Are King
04/22 19:17:23 Info: [stats] 4172mb Virtual, 1938mb Physical, 681mb Managed, 0 Handles, 114 Threads
04/22 19:17:27 Trace: [Receiver HiFi] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 1:10/4:36] In the Meantime - We Are King
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment