Core Machine (Operating system/System info/Roon build number)
Roon 1.7 build 511
Mac Mini 2018 3.2GHz 6‑core 8th‑generation Intel Core i7
Mac OS Mojave 10.14.6
32Gb RAM 2666MHz DDR4
256GB SSD storage
Network Details (Including networking gear model/manufacturer and if on WiFi/Ethernet)
185Mbps DL via Comcast DOCSIS 3 connected to
Unifi Security Gateway In turn to
Unifi 16 port managed switch
1Gbps connections on wired Ethernet to the core and library
Library is on a 6 disk Netgear ReadyNAS with dual bonded 1Gbps to the Unifi switch
3 Unifi AC-Lite access points on 2 and 5GHz provide wifi
Google CCA connected via wifi
Macbook Pro connected via wifi
Audio Devices (Specify what device you’re using and its connection type - USB/HDMI/etc.)
[FAILS] Roon Core on Mac Mini -> WiFi > RAAT -> Roon Bridge on Macbook Pro #1 -> USB -> Peachtree Nova Amp
[FAILS] Roon Core on Mac Mini -> WiFi > RAAT -> Roon Bridge on Macbook Pro #1 -> USB -> B&W Zeppelin Air
[OK] Roon Core on Mac Mini -> WiFi > RAAT -> Roon Bridge on Macbook Pro #1 -> Built in speakers
[OK] Roon Core on Mac Mini -> WiFi > RAAT -> Roon Bridge on Macbook Pro #1 -> Toslink -> Peachtree Nova Amp
[OK] Roon Core on Mac Mini -> WiFi > RAAT -> Roon Bridge on Macbook Pro #2 -> USB -> Peachtree Nova Amp
Macbook #1
- Roon Bridge v1.0 build 171
- Mac OS High Sierra 10.13.3
- MacBook Pro (Retina, 13-inch, Mid 2014)
- 2.6 GHz Intel Core i5
- 8 GB 1600 MHz DDR3
- Connected via WiFi
Macbook #2
- Roon Bridge v1.0 build 171
- Mac OS Mojave 10.14.6
- MBP 13 inch 2019
- 2.8GHz Core i7
- 16Gb RAM
Description Of Issue
Playback on MacBook Pro #1 over USB fails after about 2mins regardless of source or USB DAC being used
Dropout issues happen both with Qobus as the source or MP3s from local Library
On the same endpoint using inbuilt speakers or toslink output over 3.5mm combo jack both work just fine when selected as the zone in roon.
Using a second MacBook Pro #2 to connect via USB to the same Peachtree Nova also works perfectly
See below examples from the logs on the core of the errors at the time of the dropouts:-
Peachtree Nova - Run 1
01/09 18:48:15 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:22/4:14] Super Trouper - ABBA
01/09 18:48:20 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:27/4:14] Super Trouper - ABBA
01/09 18:48:23 Info: [stats] 7169mb Virtual, 502mb Physical, 129mb Managed, 88 Threads, FDs
01/09 18:48:25 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:32/4:14] Super Trouper - ABBA
01/09 18:48:30 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:37/4:14] Super Trouper - ABBA
01/09 18:48:35 Trace: [MBP USB Audio Dac] [zoneplayer/raat] sync USB Audio DAC : realtime=519948356170 rtt=1000us offset=300544356us delta=-142us drift=-816us in 122.0345s (-6.690ppm, -24.083ms/hr)
01/09 18:48:36 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:43/4:14] Super Trouper - ABBA
01/09 18:48:38 Info: [stats] 7166mb Virtual, 499mb Physical, 113mb Managed, 88 Threads, FDs
01/09 18:48:41 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:48/4:14] Super Trouper - ABBA
01/09 18:48:41 Debug: [raat/tcpaudiosource] disconnecting
01/09 18:48:42 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object
01/09 18:48:42 Warn: [raat/tcpaudiosource] disconnecting + retrying
01/09 18:48:42 Warn: [zoneplayer/raat] Error during streaming: System.Exception: serverside buffer overrun
at Sooloos.Broker.Transport.RaatTcpAudioSource.SendFrames (System.Net.IPEndPoint ep, System.Int32 stream_id, System.Int64 streamsample, System.Double buffersize, Sooloos.Audio.StreamFormat format, System.Double gain, System.Double peak, System.Byte[] buf, System.Int32 nsamples) [0x00608] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x00586] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
01/09 18:48:42 Warn: Track Stopped Due to Error
01/09 18:48:42 Info: [library] recorded play for profile 32fc9fa0-85f7-4978-9f15-f0f61a9096d3: mediaid=50:1:01f27368-4a91-4a5b-a751-1ae1a171da63 metadataid=123:0:MT0000129759 contentid= libraryid=50:1:01f27368-4a91-4a5b-a751-1ae1a171da63 isfromswim=False
01/09 18:48:42 Info: [zone MBP USB Audio Dac] OnPlayFeedback StoppedEndOfMediaUnnatural
01/09 18:48:42 Trace: [library] finished with 22 dirty tracks 3 dirty albums 6 dirty performers 19 dirty works 22 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 51 changed objects
01/09 18:48:42 Debug: [zone MBP USB Audio Dac] _Advance
01/09 18:48:42 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [STOPPED @ 0:00]
01/09 18:48:42 Debug: GMS: saving nav stack
01/09 18:48:42 Info: Updating seek pos to: 0
01/09 18:48:42 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 0 bytes, 0 ops in 194 ms)
01/09 18:48:42 Trace: [library] endmutation in 18ms
01/09 18:48:42 Debug: GMS: done saving nav stack
01/09 18:48:47 Trace: [zone] no playback for 5s, suspending to release audio device
01/09 18:48:47 Trace: [zone MBP USB Audio Dac] Suspend
01/09 18:48:47 Trace: [zone MBP USB Audio Dac] Stop
01/09 18:48:47 Info: [zone MBP USB Audio Dac] OnPlayFeedback Stopped
01/09 18:48:47 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/09 18:48:47 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/09 18:48:47 Info: Updating seek pos to: 0
01/09 18:48:47 Trace: [MBP USB Audio Dac] [zoneplayer/raat] Endpoint USB Audio DAC State Changed: Playing => Prepared
01/09 18:48:47 Trace: [USB Audio DAC ] [raatclient] SENT [14]{"request":"end_stream"}
01/09 18:48:47 Info: [audio/env] [zoneplayer] All streams were disposed
01/09 18:48:47 Trace: [USB Audio DAC ] [raatclient] SENT [15]{"request":"teardown"}
01/09 18:48:47 Trace: [MBP USB Audio Dac] [zoneplayer/raat] Endpoint USB Audio DAC State Changed: Prepared => Idle
01/09 18:48:47 Trace: [USB Audio DAC ] [raatclient] GOT [12] {"status":"Ended"}
01/09 18:48:47 Trace: [USB Audio DAC ] [raatclient] GOT [14] {"status":"Success"}
01/09 18:48:47 Trace: [USB Audio DAC ] [raatclient] GOT [11] {"status":"Teardown"}
01/09 18:48:47 Info: sleep 102ms after flush
01/09 18:48:53 Info: [stats] 7164mb Virtual, 500mb Physical, 130mb Managed, 85 Threads, FDs
Peachtree Nova - Run 2
01/09 18:51:30 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:05/4:14] Super Trouper - ABBA
01/09 18:51:35 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:10/4:14] Super Trouper - ABBA
01/09 18:51:39 Info: [stats] 7162mb Virtual, 506mb Physical, 131mb Managed, 83 Threads, FDs
01/09 18:51:40 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:15/4:14] Super Trouper - ABBA
01/09 18:51:45 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:20/4:14] Super Trouper - ABBA
01/09 18:51:51 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:25/4:14] Super Trouper - ABBA
01/09 18:51:54 Info: [stats] 7168mb Virtual, 512mb Physical, 156mb Managed, 83 Threads, FDs
01/09 18:51:56 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:29/4:14] Super Trouper - ABBA
01/09 18:51:57 Trace: [MBP USB Audio Dac] [zoneplayer/raat] sync USB Audio DAC : realtime=622845674834 rtt=2500us offset=202001674us delta=-1275us drift=-1324us in 91.5805s (-14.461ppm, -52.060ms/hr)
01/09 18:52:01 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:35/4:14] Super Trouper - ABBA
01/09 18:52:06 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:40/4:14] Super Trouper - ABBA
01/09 18:52:09 Info: [stats] 7164mb Virtual, 508mb Physical, 140mb Managed, 83 Threads, FDs
01/09 18:52:11 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:45/4:14] Super Trouper - ABBA
01/09 18:52:16 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:51/4:14] Super Trouper - ABBA
01/09 18:52:21 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:56/4:14] Super Trouper - ABBA
01/09 18:52:24 Info: [stats] 7166mb Virtual, 509mb Physical, 109mb Managed, 84 Threads, FDs
01/09 18:52:26 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:01/4:14] Super Trouper - ABBA
01/09 18:52:27 Trace: [MBP USB Audio Dac] [zoneplayer/raat] sync USB Audio DAC : realtime=653367520239 rtt=1000us offset=202002520us delta=-101us drift=-478us in 122.103s (-3.923ppm, -14.121ms/hr)
01/09 18:52:31 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:06/4:14] Super Trouper - ABBA
01/09 18:52:33 Debug: [raat/tcpaudiosource] disconnecting
01/09 18:52:33 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object
01/09 18:52:33 Warn: [zoneplayer/raat] Error during streaming: System.Exception: serverside buffer overrun
at Sooloos.Broker.Transport.RaatTcpAudioSource.SendFrames (System.Net.IPEndPoint ep, System.Int32 stream_id, System.Int64 streamsample, System.Double buffersize, Sooloos.Audio.StreamFormat format, System.Double gain, System.Double peak, System.Byte[] buf, System.Int32 nsamples) [0x00608] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x00586] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
01/09 18:52:33 Warn: [raat/tcpaudiosource] disconnecting + retrying
01/09 18:52:33 Warn: Track Stopped Due to Error
01/09 18:52:33 Info: [zone MBP USB Audio Dac] OnPlayFeedback StoppedEndOfMediaUnnatural
01/09 18:52:33 Info: [library] recorded play for profile 32fc9fa0-85f7-4978-9f15-f0f61a9096d3: mediaid=50:1:01f27368-4a91-4a5b-a751-1ae1a171da63 metadataid=123:0:MT0000129759 contentid= libraryid=50:1:01f27368-4a91-4a5b-a751-1ae1a171da63 isfromswim=False
01/09 18:52:33 Debug: [zone MBP USB Audio Dac] _Advance
01/09 18:52:33 Trace: [library] finished with 22 dirty tracks 3 dirty albums 6 dirty performers 19 dirty works 22 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 51 changed objects
01/09 18:52:33 Trace: [MBP USB Audio Dac] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [STOPPED @ 0:00]
01/09 18:52:33 Debug: GMS: saving nav stack
01/09 18:52:33 Info: Updating seek pos to: 0
01/09 18:52:33 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 0 bytes, 0 ops in 195 ms)
01/09 18:52:33 Trace: [library] endmutation in 21ms
01/09 18:52:34 Debug: GMS: done saving nav stack
01/09 18:52:38 Trace: [zone] no playback for 5s, suspending to release audio device
01/09 18:52:38 Trace: [zone MBP USB Audio Dac] Suspend
01/09 18:52:38 Trace: [zone MBP USB Audio Dac] Stop
01/09 18:52:38 Info: [zone MBP USB Audio Dac] OnPlayFeedback Stopped
01/09 18:52:38 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/09 18:52:38 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/09 18:52:38 Info: Updating seek pos to: 0
01/09 18:52:38 Trace: [MBP USB Audio Dac] [zoneplayer/raat] Endpoint USB Audio DAC State Changed: Playing => Prepared
01/09 18:52:38 Info: [audio/env] [zoneplayer] All streams were disposed
01/09 18:52:38 Trace: [USB Audio DAC ] [raatclient] SENT [19]{"request":"end_stream"}
01/09 18:52:38 Trace: [USB Audio DAC ] [raatclient] SENT [20]{"request":"teardown"}
01/09 18:52:38 Trace: [MBP USB Audio Dac] [zoneplayer/raat] Endpoint USB Audio DAC State Changed: Prepared => Idle
01/09 18:52:38 Trace: [USB Audio DAC ] [raatclient] GOT [17] {"status":"Ended"}
01/09 18:52:38 Trace: [USB Audio DAC ] [raatclient] GOT [19] {"status":"Success"}
01/09 18:52:39 Trace: [USB Audio DAC ] [raatclient] GOT [16] {"status":"Teardown"}
01/09 18:52:39 Info: sleep 102ms after flush
01/09 18:52:39 Info: [stats] 7161mb Virtual, 505mb Physical, 109mb Managed, 84 Threads, FDs
01/09 18:52:54 Info: [stats] 7161mb Virtual, 505mb Physical, 125mb Managed, 83 Threads, FDs
B&W Zeppelin Air - Run 1
01/09 19:31:42 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:35/4:04] Take a Chance on Me - ABBA
01/09 19:31:45 Debug: [easyhttp] POST to https://swim.roonlabs.net/1/session/d0e5b57f59694933a404f744ee5226aa/ping
01/09 19:31:46 Info: [stats] 7307mb Virtual, 666mb Physical, 171mb Managed, 92 Threads, FDs
01/09 19:31:47 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:40/4:04] Take a Chance on Me - ABBA
01/09 19:31:52 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:45/4:04] Take a Chance on Me - ABBA
01/09 19:31:57 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:49/4:04] Take a Chance on Me - ABBA
01/09 19:32:01 Info: [stats] 7305mb Virtual, 666mb Physical, 152mb Managed, 91 Threads, FDs
01/09 19:32:02 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:55/4:04] Take a Chance on Me - ABBA
01/09 19:32:03 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=116036344583 rtt=30500us offset=-37486655us delta=28328us drift=27970us in 115.914s (241.300ppm, 868.680ms/hr)
01/09 19:32:07 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:00/4:04] Take a Chance on Me - ABBA
01/09 19:32:09 Trace: [Zeppelin] [zoneplayer/raat] sync Zeppelin Air: realtime=122016258060 rtt=1500us offset=-37514741us delta=-508us drift=-116us in 121.9505s (-0.955ppm, -3.438ms/hr)
01/09 19:32:13 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:05/4:04] Take a Chance on Me - ABBA
01/09 19:32:15 Debug: [easyhttp] POST to https://swim.roonlabs.net/1/session/d0e5b57f59694933a404f744ee5226aa/ping
01/09 19:32:15 Debug: [easyhttp] POST to https://swim.roonlabs.net/1/session/d0e5b57f59694933a404f744ee5226aa/ping returned after 117 ms, status code: 200
01/09 19:32:15 Warn: [easyhttp] web exception without response: Error getting response stream (ReadDoneAsync2): ReceiveFailure
01/09 19:32:15 Critical: scx: System.Exception: /session/ping failed: 999
at Sooloos.Broker.Music.Radio.SwimProxy.Ping (System.String session_id) [0x001b7] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Music.Radio.MLRadioSession.<Init>b__36_3 () [0x00070] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at System.Runtime.CompilerServices.AsyncMethodBuilderCore+<>c.<ThrowAsync>b__7_0 (System.Object state) [0x00000] in <ba70b91736bd40cb990a357097dba9c3>:0
at Sooloos.SynchronizationContextThread._Dispatch (Sooloos.SynchronizationContextThread+SendOrPostWrapper& ret) [0x00012] in <4351bba580eb4f198ba49f66f69991bd>:0
01/09 19:32:15 Debug: [raat/tcpaudiosource] disconnecting
01/09 19:32:15 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object
01/09 19:32:15 Warn: [raat/tcpaudiosource] disconnecting + retrying
01/09 19:32:15 Warn: [zoneplayer/raat] Error during streaming: System.Exception: serverside buffer overrun
at Sooloos.Broker.Transport.RaatTcpAudioSource.SendFrames (System.Net.IPEndPoint ep, System.Int32 stream_id, System.Int64 streamsample, System.Double buffersize, Sooloos.Audio.StreamFormat format, System.Double gain, System.Double peak, System.Byte[] buf, System.Int32 nsamples) [0x00608] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x00586] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
01/09 19:32:15 Warn: Track Stopped Due to Error
01/09 19:32:15 Info: [zone Zeppelin] OnPlayFeedback StoppedEndOfMediaUnnatural
01/09 19:32:15 Info: [library] recorded play for profile 32fc9fa0-85f7-4978-9f15-f0f61a9096d3: mediaid=50:1:abc00b3a-b723-48c4-b18c-bbc22f87df3f metadataid=123:0:MT0005241099 contentid= libraryid=50:1:abc00b3a-b723-48c4-b18c-bbc22f87df3f isfromswim=False
01/09 19:32:15 Debug: [zone Zeppelin] _Advance
01/09 19:32:15 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [STOPPED @ 0:00]
01/09 19:32:15 Trace: [library] finished with 22 dirty tracks 3 dirty albums 6 dirty performers 19 dirty works 22 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 51 changed objects
01/09 19:32:15 Debug: GMS: saving nav stack
01/09 19:32:15 Info: Updating seek pos to: 0
01/09 19:32:15 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 0 bytes, 0 ops in 272 ms)
01/09 19:32:15 Trace: [library] endmutation in 23ms
01/09 19:32:15 Debug: GMS: done saving nav stack
01/09 19:32:16 Info: [stats] 7303mb Virtual, 666mb Physical, 182mb Managed, 91 Threads, FDs
01/09 19:32:20 Trace: [zone] no playback for 5s, suspending to release audio device
01/09 19:32:20 Trace: [zone Zeppelin] Suspend
01/09 19:32:20 Trace: [zone Zeppelin] Stop
01/09 19:32:20 Info: [zone Zeppelin] OnPlayFeedback Stopped
01/09 19:32:20 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/09 19:32:20 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Playing => Prepared
01/09 19:32:20 Info: Updating seek pos to: 0
01/09 19:32:20 Trace: [Zeppelin Air] [raatclient] SENT [9]{"request":"end_stream"}
01/09 19:32:20 Trace: [Zeppelin Air] [raatclient] SENT [10]{"request":"teardown"}
01/09 19:32:20 Info: [audio/env] [zoneplayer] All streams were disposed
01/09 19:32:20 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Prepared => Idle
01/09 19:32:20 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/09 19:32:20 Trace: [Zeppelin Air] [raatclient] GOT [7] {"status":"Ended"}
01/09 19:32:20 Trace: [Zeppelin Air] [raatclient] GOT [9] {"status":"Success"}
01/09 19:32:20 Info: sleep 102ms after flush
01/09 19:32:20 Trace: [Zeppelin Air] [raatclient] GOT [6] {"status":"Teardown"}
01/09 19:32:31 Info: [stats] 7315mb Virtual, 666mb Physical, 146mb Managed, 96 Threads, FDs
B&W Zeppelin Air - Run 2
01/09 19:35:12 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:30/3:52] Dancing Queen - ABBA
01/09 19:35:14 Trace: [Zeppelin] [zoneplayer/raat] sync Zeppelin Air: realtime=224924790088 rtt=1500us offset=-119449209us delta=269us drift=12us in 91.61s (0.139ppm, 0.501ms/hr)
01/09 19:35:16 Info: [stats] 7299mb Virtual, 670mb Physical, 166mb Managed, 86 Threads, FDs
01/09 19:35:17 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:35/3:52] Dancing Queen - ABBA
01/09 19:35:23 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:40/3:52] Dancing Queen - ABBA
01/09 19:35:28 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:45/3:52] Dancing Queen - ABBA
01/09 19:35:31 Info: [stats] 7299mb Virtual, 670mb Physical, 143mb Managed, 86 Threads, FDs
01/09 19:35:33 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:51/3:52] Dancing Queen - ABBA
01/09 19:35:38 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:56/3:52] Dancing Queen - ABBA
01/09 19:35:43 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:01/3:52] Dancing Queen - ABBA
01/09 19:35:44 Trace: [Zeppelin] [zoneplayer/raat] sync Zeppelin Air: realtime=255530387645 rtt=21000us offset=-119431612us delta=18284us drift=17610us in 122.1785s (144.136ppm, 518.889ms/hr)
01/09 19:35:46 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=257646875449 rtt=50000us offset=-119404124us delta=27487us drift=45098us in 124.2385s (362.996ppm, 1306.787ms/hr)
01/09 19:35:46 Info: [stats] 7299mb Virtual, 670mb Physical, 163mb Managed, 86 Threads, FDs
01/09 19:35:48 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:06/3:52] Dancing Queen - ABBA
01/09 19:35:50 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=616426001645603065 rtt=47500us offset=616425620535603us delta=616425739985296us drift=616425739984825us in 128.3s (4804565393490.460ppm, 17296435416565.600ms/hr)
01/09 19:35:50 Debug: [prebuffer] sleeping in read -- this isn't good
01/09 19:35:50 Debug: [raat/tcpaudiosource] disconnecting
01/09 19:35:50 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object
01/09 19:35:50 Warn: [raat/tcpaudiosource] disconnecting + retrying
01/09 19:35:50 Warn: [zoneplayer/raat] Error during streaming: System.Exception: serverside buffer overrun
at Sooloos.Broker.Transport.RaatTcpAudioSource.SendFrames (System.Net.IPEndPoint ep, System.Int32 stream_id, System.Int64 streamsample, System.Double buffersize, Sooloos.Audio.StreamFormat format, System.Double gain, System.Double peak, System.Byte[] buf, System.Int32 nsamples) [0x00608] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x00586] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
01/09 19:35:50 Warn: Track Stopped Due to Error
01/09 19:35:50 Info: [zone Zeppelin] OnPlayFeedback StoppedEndOfMediaUnnatural
01/09 19:35:50 Debug: [zone Zeppelin] _Advance
01/09 19:35:50 Info: [library] recorded play for profile 32fc9fa0-85f7-4978-9f15-f0f61a9096d3: mediaid=50:1:65f0a82e-455a-4ad2-849b-6f6f25b5f0a0 metadataid=123:0:MT0001899946 contentid= libraryid=50:1:65f0a82e-455a-4ad2-849b-6f6f25b5f0a0 isfromswim=False
01/09 19:35:50 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [STOPPED @ 0:00]
01/09 19:35:50 Trace: [library] finished with 22 dirty tracks 3 dirty albums 6 dirty performers 19 dirty works 22 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 51 changed objects
01/09 19:35:50 Debug: GMS: saving nav stack
01/09 19:35:50 Info: Updating seek pos to: 0
01/09 19:35:50 Trace: [push] restarting connection (Unable to read data from the transport connection: interrupted.)
01/09 19:35:50 Trace: [push] retrying connection in 65938ms
01/09 19:35:50 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 0 bytes, 0 ops in 282 ms)
01/09 19:35:50 Trace: [library] endmutation in 51ms
01/09 19:35:51 Debug: GMS: done saving nav stack
01/09 19:35:55 Trace: [zone] no playback for 5s, suspending to release audio device
01/09 19:35:55 Trace: [zone Zeppelin] Suspend
01/09 19:35:55 Trace: [zone Zeppelin] Stop
01/09 19:35:55 Info: [zone Zeppelin] OnPlayFeedback Stopped
01/09 19:35:55 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/09 19:35:55 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Playing => Prepared
01/09 19:35:55 Trace: [Zeppelin Air] [raatclient] SENT [14]{"request":"end_stream"}
01/09 19:35:55 Trace: [Zeppelin Air] [raatclient] SENT [15]{"request":"teardown"}
01/09 19:35:55 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/09 19:35:55 Info: [audio/env] [zoneplayer] All streams were disposed
01/09 19:35:55 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Prepared => Idle
01/09 19:35:55 Trace: [Zeppelin Air] [raatclient] GOT [12] {"status":"Ended"}
01/09 19:35:55 Trace: [Zeppelin Air] [raatclient] GOT [14] {"status":"Success"}
01/09 19:35:55 Info: Updating seek pos to: 0
01/09 19:35:55 Trace: [Zeppelin Air] [raatclient] GOT [11] {"status":"Teardown"}
01/09 19:35:56 Info: sleep 102ms after flush
01/09 19:36:01 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
01/09 19:36:01 Trace: [push] connecting to 104.196.62.96:9200
01/09 19:36:01 Trace: [push] connected
01/09 19:36:01 Info: [stats] 7312mb Virtual, 670mb Physical, 134mb Managed, 92 Threads, FDs
B&W Zeppelin Air - Run 3
01/09 19:38:17 Info: [stats] 7309mb Virtual, 678mb Physical, 150mb Managed, 88 Threads, FDs
01/09 19:38:17 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=356335749322 rtt=30500us offset=-171407250us delta=-19172us drift=23123us in 89.469s (258.457ppm, 930.446ms/hr)
01/09 19:38:18 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:30/4:30] Poison - Alice Cooper
01/09 19:38:19 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=358384076591 rtt=31000us offset=-171401923us delta=5327us drift=28451us in 91.5115s (310.903ppm, 1119.250ms/hr)
01/09 19:38:21 Trace: [Zeppelin] [zoneplayer/raat] sync Zeppelin Air: realtime=360327458605 rtt=1500us offset=-171431541us delta=-29617us drift=-1166us in 93.514s (-12.477ppm, -44.918ms/hr)
01/09 19:38:23 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:35/4:30] Poison - Alice Cooper
01/09 19:38:25 Warn: [Zeppelin] [zoneplayer/raat] long rtt sync Zeppelin Air: realtime=364546328031 rtt=49500us offset=-171385671us delta=44910us drift=44702us in 97.639s (457.836ppm, 1648.209ms/hr)
01/09 19:38:29 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:41/4:30] Poison - Alice Cooper
01/09 19:38:32 Info: [stats] 7309mb Virtual, 678mb Physical, 194mb Managed, 88 Threads, FDs
01/09 19:38:34 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:46/4:30] Poison - Alice Cooper
01/09 19:38:39 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:51/4:30] Poison - Alice Cooper
01/09 19:38:44 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 1:56/4:30] Poison - Alice Cooper
01/09 19:38:47 Info: [stats] 7309mb Virtual, 679mb Physical, 147mb Managed, 88 Threads, FDs
01/09 19:38:49 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:01/4:30] Poison - Alice Cooper
01/09 19:38:52 Trace: [Zeppelin] [zoneplayer/raat] sync Zeppelin Air: realtime=390881508979 rtt=1500us offset=-171430491us delta=412us drift=-116us in 124.067s (-0.938ppm, -3.378ms/hr)
01/09 19:38:54 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [PLAYING @ 2:06/4:30] Poison - Alice Cooper
01/09 19:38:56 Debug: [raat/tcpaudiosource] disconnecting
01/09 19:38:56 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object
01/09 19:38:56 Warn: [raat/tcpaudiosource] disconnecting + retrying
01/09 19:38:56 Warn: [zoneplayer/raat] Error during streaming: System.Exception: serverside buffer overrun
at Sooloos.Broker.Transport.RaatTcpAudioSource.SendFrames (System.Net.IPEndPoint ep, System.Int32 stream_id, System.Int64 streamsample, System.Double buffersize, Sooloos.Audio.StreamFormat format, System.Double gain, System.Double peak, System.Byte[] buf, System.Int32 nsamples) [0x00608] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
at Sooloos.Broker.Transport.RaatZonePlayer+<>c__DisplayClass31_0.<_StartStream4>b__1 () [0x00586] in <d343d8c3cd7d44fea23ecaf77bfe6405>:0
01/09 19:38:56 Warn: Track Stopped Due to Error
01/09 19:38:56 Info: [zone Zeppelin] OnPlayFeedback StoppedEndOfMediaUnnatural
01/09 19:38:56 Info: [library] recorded play for profile 32fc9fa0-85f7-4978-9f15-f0f61a9096d3: mediaid=50:1:d94e3c05-3b28-4401-be26-bb03562ef5e4 metadataid= contentid= libraryid=50:1:d94e3c05-3b28-4401-be26-bb03562ef5e4 isfromswim=False
01/09 19:38:56 Debug: [zone Zeppelin] _Advance
01/09 19:38:56 Trace: [library] finished with 1 dirty tracks 1 dirty albums 1 dirty performers 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 3 changed objects
01/09 19:38:56 Trace: [Zeppelin] [LowQuality, 24/44 MP3 => 16/44] [100% buf] [STOPPED @ 0:00]
01/09 19:38:56 Debug: GMS: saving nav stack
01/09 19:38:56 Info: Updating seek pos to: 0
01/09 19:38:56 Debug: GMS: done saving nav stack
01/09 19:39:01 Trace: [zone] no playback for 5s, suspending to release audio device
01/09 19:39:01 Trace: [zone Zeppelin] Suspend
01/09 19:39:01 Trace: [zone Zeppelin] Stop
01/09 19:39:01 Info: [zone Zeppelin] OnPlayFeedback Stopped
01/09 19:39:01 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
01/09 19:39:01 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
01/09 19:39:01 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Playing => Prepared
01/09 19:39:01 Trace: [Zeppelin Air] [raatclient] SENT [19]{"request":"end_stream"}
01/09 19:39:01 Trace: [Zeppelin Air] [raatclient] SENT [20]{"request":"teardown"}
01/09 19:39:01 Info: [audio/env] [zoneplayer] All streams were disposed
01/09 19:39:01 Info: Updating seek pos to: 0
01/09 19:39:01 Trace: [Zeppelin] [zoneplayer/raat] Endpoint Zeppelin Air State Changed: Prepared => Idle
01/09 19:39:01 Trace: [Zeppelin Air] [raatclient] GOT [17] {"status":"Ended"}
01/09 19:39:01 Trace: [Zeppelin Air] [raatclient] GOT [19] {"status":"Success"}
01/09 19:39:01 Trace: [Zeppelin Air] [raatclient] GOT [16] {"status":"Teardown"}
01/09 19:39:01 Info: sleep 102ms after flush
01/09 19:39:02 Info: [stats] 7316mb Virtual, 679mb Physical, 181mb Managed, 91 Threads, FDs