Roon Core Machine
IBM Intel i3 home server (TS140, SSD, 20GB RAM)
Ubuntu server 22.04.1 LTS (Linux 5.15.0-52-generic x86_64)
All files on local (spinning) disk.
Wired network
Roon v2.0 (Build 1143) production
Networking Gear & Setup Details
ASUS GT-AX1000
ASUS RT-AX92U
Connected Audio Devices
Matrix Mini-i Pro 3
Hardware: V1414
Firmware: D013B8
WiFi Network or Ethernet
Number of Tracks in Library
96459
Description of Issue
When connecting via RAAT to the Matrix Mini-i pro, radio does not last more than a few minutes (I tried both WiFi and Ethernet connected). It stops with the error shown in the screenshot below:
The same radio station plays without issue if I configure the Matrix Mini-i pro to use Airplay instead.
The Matrix Mini and RAAT does not have an issue playing regular library (local or tidal) tracks.
Edit:
On a whim I tested using an alternate URL for the radio station (WBEZ)
The default Roon URL for WBEZ is:
http://wbez.iad.streamguys1.com/wbezlinear
When using
http://stream.wbez.org/wbez128.mp3 or http://stream.wbez.org/wbezlinear
The stream played without issue on the Matrix Mini using both RAAT and Airplay.
Edit 3:
This a trace with just the matrix-pro playing WFMT hardwired to my hub.
11/08 12:53:26 Info: sleep 570ms after flush
11/08 12:53:27 Debug: [easyhttp] [2442] GET to https://wfmt.streamguys1.com/main-source returned after 845 ms, status code: 200
11/08 12:53:27 Trace: [internetradio] Channel Player: Fetching URL [https://wowza.wfmt.com/wfmt/256/playlist.m3u8]
11/08 12:53:27 Trace: [roondns] flushed 48 last-known-good entries
11/08 12:53:27 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [754] {"status":"Success"}
11/08 12:53:27 Debug: [easyhttp] [2444] GET to https://wowza.wfmt.com/wfmt/256/playlist.m3u8 returned after 377 ms, status code: 200
11/08 12:53:27 Trace: [internetradio] Opening URL List https://wowza.wfmt.com/wfmt/256/playlist.m3u8
11/08 12:53:27 Trace: [internetradio] Channel Player: Fetching URL [https://www.wfmt.com/res/streaming/live/wfmtmp3stream.m3u]
11/08 12:53:27 Debug: [easyhttp] [2445] GET to https://www.wfmt.com/res/streaming/live/wfmtmp3stream.m3u returned after 196 ms, status code: 200
11/08 12:53:27 Trace: [internetradio] Opening URL List https://www.wfmt.com/res/streaming/live/wfmtmp3stream.m3u
11/08 12:53:27 Trace: [internetradio] Found URL http://stream.wfmt.com/main
11/08 12:53:27 Trace: [internetradio] Channel Player: Fetching URL [https://stream.wfmt.com/main128.aac]
11/08 12:53:28 Info: [stats] 24248mb Virtual, 2733mb Physical, 1295mb Managed, 433 Handles, 87 Threads
11/08 12:53:28 Debug: [easyhttp] [2446] GET to https://stream.wfmt.com/main128.aac returned after 1178 ms, status code: 200
11/08 12:53:28 Trace: [internetradio] Channel Player: Fetching URL [https://stream.wfmt.com/main-mp3]
11/08 12:53:29 Debug: [easyhttp] [2447] GET to https://stream.wfmt.com/main-mp3 returned after 587 ms, status code: 200
11/08 12:53:29 Trace: Attempting to play channel URL: icy://wfmt.streamguys1.com/main-source
11/08 12:53:29 Info: [Studio] [zoneplayer] Playing: channel://icy%3a%2f%2fwfmt.streamguys1.com%2fmain-source
11/08 12:53:31 Info: [Studio] [zoneplayer] Open Result (Playing):Result[Status=Success]
11/08 12:53:31 Trace: [Studio] [LowQuality, 24/44 AAC => 24/44] [LOADING @ 0:00] WFMT
11/08 12:53:31 Info: [Studio] [zoneplayer] Starting playback
11/08 12:53:31 Trace: [Studio] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=48000, isdts=False) => StreamFormat(channels=2, bitspersample=24, samplerate=48000, isdts=False) streamid=1548117099
11/08 12:53:31 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] SENT [1067]{"request":"teardown"}
11/08 12:53:31 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Prepared => Idle
11/08 12:53:31 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] SENT [1068]{"request":"setup","format":{"sample_type":"pcm","sample_rate":48000,"bits_per_sample":24,"channels":2,"sample_subtype":"none"}}
11/08 12:53:31 Info: [zone Studio] OnPlayFeedback Playing
11/08 12:53:31 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:00] WFMT
11/08 12:53:31 Debug: [easyhttp] [2448] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack& returned after 246 ms, status code: 200
11/08 12:53:31 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = LowQuality
Elements:
Source Format=Aac 48000/24/2 Quality=LowQuality
Raat Device=Matrix Audio mini-i Pro 3
Output OutputType=Local_Analog Quality=Lossless SubType= Model=
------------------------------------------------------------
11/08 12:53:33 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [241] {"status":"Teardown"}
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1068] {"status":"OutputMessage","message":{"signal_path":[{"quality":"lossless","method":"analog","type":"output"}]}}
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1068] {"status":"Success","audio_port_tcp":41855,"clock_port":48805,"audio_port":0}
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Idle => Prepared
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] pausing for 0.1s for server-side resync delay
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] synced to endpoint Matrix Audio mini-i Pro 3 clock: realtime=670566789668 rtt=1000us offset=-137816210us delta=-137816210us
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] SENT [1110]{"request":"stream","stream_id":1548117099,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1110] {"status":"Buffering"}
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Prepared => Buffering
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] [zoneplayer/raat] selected Matrix Audio mini-i Pro 3 as clock master
11/08 12:53:34 Debug: [raat/tcpaudiosource] connecting to 192.168.1.207:41855
11/08 12:53:34 Debug: [raat/tcpaudiosource] connected
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1110] {"status":"Ready"}
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Buffering => Ready
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] wait for ready in 73ms
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Adjusting playback start offset from 50ms to 590ms
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] SENT [1111]{"request":"start","min_offset":590791666,"stream_sample":0}
11/08 12:53:34 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Ready => Playing
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1110] {"status":"Playing"}
11/08 12:53:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1111] {"status":"Success","time":671276525000}
11/08 12:53:36 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:02] WFMT
11/08 12:53:41 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:07] WFMT
11/08 12:53:42 Warn: [Studio] [zoneplayer/raat] long rtt sync Matrix Audio mini-i Pro 3: realtime=678787628667 rtt=38500us offset=-137781371us delta=33859us drift=34838us in 8.1485s (4275.511ppm, 15391.839ms/hr)
11/08 12:53:42 Trace: Got ICY Stream Title [Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA]
11/08 12:53:42 Trace: [music/search] starting internet radio search
11/08 12:53:43 Debug: [easyhttp] [2450] GET to https://search.roonlabs.net/1/internetradiosearch?q=Benjamin%20Britten%20-%20Gloriana%20-%20Julian%20Bream%20Consort%20-%20Julian%20Bream%20Edition%20Vol%206%3A%20The%20Julian%20Bream%20Consort%20-%20RCA&count=50&c=tidal-us returned after 284 ms, status code: 200
11/08 12:53:43 Trace: [music/search] [performance][internetradio] end-to-end search took 867ms
11/08 12:53:43 Info: [broker] [liveradio] Got no track result for subtitle 'Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA'
11/08 12:53:43 Info: [stats] 24256mb Virtual, 2736mb Physical, 1332mb Managed, 435 Handles, 92 Threads
11/08 12:53:46 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:12] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:53:50 Warn: [Studio] [zoneplayer/raat] long rtt sync Matrix Audio mini-i Pro 3: realtime=687060183167 rtt=94000us offset=-137728816us delta=86412us drift=87393us in 16.304s (5360.249ppm, 19296.896ms/hr)
11/08 12:53:52 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:17] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:53:52 Warn: [Studio] [zoneplayer/raat] long rtt sync Matrix Audio mini-i Pro 3: realtime=689009127667 rtt=106000us offset=-137714872us delta=13944us drift=101337us in 18.235s (5557.335ppm, 20006.405ms/hr)
11/08 12:53:57 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:22] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:53:58 Info: [stats] 24337mb Virtual, 2736mb Physical, 1323mb Managed, 435 Handles, 101 Threads
11/08 12:54:02 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:27] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:04 Trace: [Studio] [zoneplayer/raat] sync Matrix Audio mini-i Pro 3: realtime=700874158001 rtt=1000us offset=-137815841us delta=248us drift=368us in 30.306s (12.154ppm, 43.754ms/hr)
11/08 12:54:07 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:32] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:13 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:38] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:13 Info: [stats] 24280mb Virtual, 2736mb Physical, 1321mb Managed, 435 Handles, 91 Threads
11/08 12:54:18 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:43] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:21 Trace: [push2] retrying connection in 0ms
11/08 12:54:21 Trace: [broker/accounts] network reachability changed. refreshing
11/08 12:54:21 Trace: [broker/accounts] [heartbeat] now=11/8/2022 6:54:21 PM nextauthrefresh=11/8/2022 7:47:45 PM nextmachineallocate=11/8/2022 10:47:45 PM
11/08 12:54:21 Trace: [broker/accounts] doing auth refresh, next=11/8/2022 7:47:45 PM
11/08 12:54:21 Trace: [broker/accounts] refreshing account info for email='XXX@XXX.com' userid=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:21 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
11/08 12:54:21 Trace: [fiveaccountserver] BODY token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23
11/08 12:54:21 Trace: [push2] exception thrown. restarting connection (The operation was canceled.)
11/08 12:54:21 Debug: [tidal] network reachability changed. refreshing token
11/08 12:54:21 Trace: [raat] [sood] Refreshing device list
11/08 12:54:21 Trace: [raatserver] [sood] Refreshing device list
11/08 12:54:21 Trace: [mobile] [remoteconnectivity] Port Verification started due to: network reachability changed, port verification not in progress, starting a new attempt
11/08 12:54:21 Trace: [roonapi] network reachability changed. Kicking off discovery cycle
11/08 12:54:21 Trace: [roondns] flushed 48 last-known-good entries
11/08 12:54:21 Debug: [easyhttp] [2454] GET to https://internetradio.roonlabs.net/2/api/location?format=msgpack& returned after 235 ms, status code: 200
11/08 12:54:21 Trace: [radio/library] got location US
11/08 12:54:21 Debug: [easyhttp] [2453] GET to https://push-manager.roonlabs.net/1/connect returned after 257 ms, status code: 200
11/08 12:54:21 Debug: [push2] push connector url received from push manager: ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
11/08 12:54:21 Trace: [push2] connecting to push2 connector at ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
11/08 12:54:21 Debug: [easyhttp] [2451] POST to https://discovery.roonlabs.net/1/query returned after 265 ms, status code: 200
11/08 12:54:21 Debug: [easyhttp] [2452] POST to https://accounts5.roonlabs.com/accounts/3/login returned after 266 ms, status code: 200
11/08 12:54:21 Trace: [fiveaccountserver] GOT {"status":"Success","userid":"a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e","token":"0a17a2ad-3edd-45fa-8d93-fdfd13ccad23","expiration":30,"email":"XXX@XXX.com","groups":[]}
11/08 12:54:21 Debug: [easyhttp] [2456] GET to https://porttest.roonlabs.net/1/myip returned after 278 ms, status code: 200
11/08 12:54:21 Debug: [easyhttp] [2455] GET to https://oauthcb.roonlabs.net/2/tidal/refresh?token=eyJraWQiOiJoUzFKYTdVMCIsImFsZyI6IkVTNTEyIn0.eyJ0eXBlIjoibzJfcmVmcmVzaCIsInVpZCI6NDA3MTY2NTQsInNjb3BlIjoid19zdWIgcl91c3Igd191c3IiLCJjaWQiOjI0MjUsInNWZXIiOjAsImdWZXIiOjAsImlzcyI6Imh0dHBzOi8vYXV0aC50aWRhbC5jb20vdjEifQ.ANgn4ajIa9GWhMTrLY6thLJTF-50VK5HiNapTRUdoVXuDlrpLpOoGXNwzkSUim2GsmC7s1C3RptfMGlNAtob6srGATYf7mlqHezGI61RPnLmYmUAvRbWe5R9nemfu0pw-z1Qfwxm-Xjsf-YDYCvppL9IBBeDKakMxMmmOcr4tqzr45gH returned after 463 ms, status code: 200
11/08 12:54:21 Info: [broker/locations] updating location Tidal:Name=TIDAL:Id=a2bc918b-81fb-abc3-f24b-19bcfed910a2
11/08 12:54:21 Trace: [push2] connected to push2 connector at ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
11/08 12:54:21 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23
11/08 12:54:21 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23&machineid=d6e9ef3c-ce83-7f57-d468-7714c0d0c5c4
11/08 12:54:21 Trace: [broker/accounts] updated token. New expiration is 12/8/2022 12:54:21 PM
11/08 12:54:21 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:21 Trace: [bits] myinfo: {"pushid":"broker/6b6404a7-20f8-42ab-9168-3f813d617703","roon_auth_token":"0a17a2ad-3edd-45fa-8d93-fdfd13ccad23","os":"Linux 5.15.0-52-generic","platform":"linuxx64","machineversion":200001143,"branch":"production","appmodifier":"","appname":"RoonServer"}
11/08 12:54:21 Trace: [roondns] flushed 48 last-known-good entries
11/08 12:54:21 Debug: [easyhttp] [2459] GET to https://api.tidal.com/v1/sessions/770bd0ce-036d-4605-a466-15877babebda?countryCode=US returned after 165 ms, status code: 200
11/08 12:54:21 Trace: [tidal/http] GET https://api.tidal.com/v1/sessions/770bd0ce-036d-4605-a466-15877babebda?countryCode=US => Success
11/08 12:54:21 Info: [mobile] GOT HTTP API /portverify/b5704d4f-233b-420d-826e-b72964a40222
11/08 12:54:21 Trace: [mobile] Got PortVerify Request from_guid=b5704d4f-233b-420d-826e-b72964a40222
11/08 12:54:21 Trace: [mobile] Returning guid for verification: d7f34dcc-5ae7-45f0-9be2-5d474213dc8a
11/08 12:54:21 Debug: [easyhttp] [2461] GET to https://accounts5.roonlabs.com/accounts/3/profileslist?token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23 returned after 218 ms, status code: 200
11/08 12:54:21 Trace: [fiveaccountserver] GOT {"status":"Success","profiles":[{"id":"00ffce3d-cc14-4991-ad7c-b8a93097bf09","name":"Guest","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--00ffce3d-cc14-4991-ad7c-b8a93097bf09.jpg","birthdate":"19250000"},{"id":"c4b9a06e-3f41-4ffb-96bc-72b17c9d75bc","name":"Cait","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--c4b9a06e-3f41-4ffb-96bc-72b17c9d75bc.jpg","birthdate":"19660000"},{"id":"750431c7-6ad5-43ac-9770-5adfbb438d0e","name":"Shaun","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--750431c7-6ad5-43ac-9770-5adfbb438d0e.jpg","birthdate":"19680000"},{"id":"2253db46-bffd-4ebe-bf49-550548c83c79","name":"Jim","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--2253db46-bffd-4ebe-bf49-550548c83c79.jpg","birthdate":"19600000"},{"id":"b7e93721-fe93-4900-bf67-7c35293813f8","name":"Stuart","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--b7e93721-fe93-4900-bf67-7c35293813f8.jpg","birthdate":"19680000"},{"id":"95fd84db-90dd-4d67-9aec-15fe7887ae6b","name":"Paul","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--95fd84db-90dd-4d67-9aec-15fe7887ae6b.jpg","birthdate":"19691205"},{"id":"c71c5513-43a8-4983-8edd-0ea7989832e4","name":"Colby","photo":"https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--c71c5513-43a8-4983-8edd-0ea7989832e4.jpg","birthdate":"19661217"}]}
11/08 12:54:21 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:21 Debug: [easyhttp] [2462] GET to https://accounts5.roonlabs.com/accounts/3/userinfo?token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23&machineid=d6e9ef3c-ce83-7f57-d468-7714c0d0c5c4 returned after 229 ms, status code: 200
11/08 12:54:21 Trace: [fiveaccountserver] GOT {"user":{"tfa":{"enabled":false},"userid":"a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e","firstname":"stuart","lastname":"hannay","email":"XXX@XXX.com","joinmailinglist":true,"allowpushnotifications":true,"class":"Normal","groups":[],"dncs":[],"trialallowed":false},"status":"Success"}
11/08 12:54:21 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:21 Debug: [easyhttp] [2458] POST to https://porttest.roonlabs.net/1/port/check returned after 437 ms, status code: 200
11/08 12:54:21 Trace: [roondns] flushed 48 last-known-good entries
11/08 12:54:21 Debug: [easyhttp] [2463] POST to https://bits.roonlabs.net/1/q/roon.base.,roon.internet_discovery.,roon.debug.,roon.broker.,roon.dsp.,roon.sood.?roon_auth_token=0a17a2ad-3edd-45fa-8d93-fdfd13ccad23 returned after 289 ms, status code: 200
11/08 12:54:21 Trace: [bits] updated bits, in 291ms
11/08 12:54:21 Debug: [easyhttp] [2464] GET to https://api.tidal.com/v1/users/40716654/subscription?countryCode=US returned after 181 ms, status code: 200
11/08 12:54:21 Trace: [tidal/http] GET https://api.tidal.com/v1/users/40716654/subscription?countryCode=US => Success
11/08 12:54:21 Trace: [tidal] transition loginstatus from LoginSucceeded to LoginSucceeded
11/08 12:54:22 Info: [mobile] GOT HTTP API /hello
11/08 12:54:22 Trace: [mobile] Got Hello Request body={"coreId":"6b6404a7-20f8-42ab-9168-3f813d617703"}
11/08 12:54:22 Debug: [easyhttp] [2466] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--c4b9a06e-3f41-4ffb-96bc-72b17c9d75bc.jpg?random=189d27bc-bbf9-4cae-bcda-e5fd1e9cc709 returned after 285 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Debug: [easyhttp] [2460] POST to https://roonmobile.roonlabs.net/1/cores/announce returned after 532 ms, status code: 200
11/08 12:54:22 Debug: [easyhttp] [2467] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--750431c7-6ad5-43ac-9770-5adfbb438d0e.jpg?random=579db1d1-8754-4c96-88ba-6ce1a785a2c4 returned after 294 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Debug: [easyhttp] [2468] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--b7e93721-fe93-4900-bf67-7c35293813f8.jpg?random=1ac12c12-a8ac-4677-8dd5-d0a63b50d9a0 returned after 309 ms, status code: 200
11/08 12:54:22 Debug: [easyhttp] [2470] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--95fd84db-90dd-4d67-9aec-15fe7887ae6b.jpg?random=a868be74-d6be-4dc1-8a02-2208160d7104 returned after 309 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Debug: [easyhttp] [2465] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--00ffce3d-cc14-4991-ad7c-b8a93097bf09.jpg?random=fff598f6-fd77-4421-aa17-c4e697819e1f returned after 311 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Debug: [easyhttp] [2471] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--c71c5513-43a8-4983-8edd-0ea7989832e4.jpg?random=af1400da-42e2-47d1-a784-183d9ff5f804 returned after 342 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Debug: [easyhttp] [2469] GET to https://sooloos-profileimages.s3.amazonaws.com/five-a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e--2253db46-bffd-4ebe-bf49-550548c83c79.jpg?random=9793b9bc-1864-42ff-899f-093a452ff91a returned after 367 ms, status code: 200
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=a6f0e9b0-0b0d-4891-b1b9-dbf0ba65823e
11/08 12:54:22 Info: [mobile] GOT HTTP API /hello
11/08 12:54:22 Trace: [mobile] Got Hello Request body={"coreId":"6b6404a7-20f8-42ab-9168-3f813d617703"}
11/08 12:54:22 Debug: [easyhttp] [2472] POST to https://roonmobile.roonlabs.net/1/cores/announce returned after 466 ms, status code: 200
11/08 12:54:22 Info: [mobile] GOT HTTP API /hello
11/08 12:54:22 Trace: [mobile] Got Hello Request body={"coreId":"6b6404a7-20f8-42ab-9168-3f813d617703"}
11/08 12:54:22 Debug: [easyhttp] [2473] POST to https://roonmobile.roonlabs.net/1/cores/announce returned after 397 ms, status code: 200
11/08 12:54:23 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:48] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:23 Trace: [remoting/brokerserver] network reachability changed. Kicking off discovery cycle
11/08 12:54:26 Debug: [easyhttp] [2474] POST to https://discovery.roonlabs.net/1/register returned after 219 ms, status code: 200
11/08 12:54:26 Trace: [inetdiscovery] registered 1 devices, 5 services
11/08 12:54:28 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:53] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:28 Info: [stats] 24441mb Virtual, 2736mb Physical, 1348mb Managed, 440 Handles, 114 Threads
11/08 12:54:32 Debug: [easyhttp] [2476] POST to https://device-map.roonlabs.net/1/register returned after 315 ms, status code: 200
11/08 12:54:32 Trace: [devicemap] device map updated
11/08 12:54:33 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [PLAYING @ 0:58] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:33 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1110] {"status":"Dropout","samples":337285}
11/08 12:54:33 Warn: [Studio] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
11/08 12:54:33 Trace: [Studio] [zoneplayer/raat] too many dropouts. stopping stream
11/08 12:54:33 Trace: [Studio] [zoneplayer/raat] Endpoint Matrix Audio mini-i Pro 3 State Changed: Playing => Prepared
11/08 12:54:33 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
11/08 12:54:33 Warn: [zone Studio] Track Stopped Due to Slow Media
11/08 12:54:33 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
11/08 12:54:33 Trace: Attempting to play channel URL: icy://wfmt.streamguys1.com/main-source
11/08 12:54:33 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] SENT [2680]{"request":"end_stream"}
11/08 12:54:33 Debug: [raat/tcpaudiosource] disconnecting
11/08 12:54:33 Info: [zone Studio] OnPlayFeedback StoppedEndOfMediaUnnatural
11/08 12:54:33 Info: [zone Studio] OnPlayFeedback Stopped
11/08 12:54:33 Info: [zone Studio] Canceling Pending Sleep
11/08 12:54:33 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object.
11/08 12:54:33 Warn: [raat/tcpaudiosource] disconnecting + retrying
11/08 12:54:33 Info: [zone Studio] Canceling Pending Sleep
11/08 12:54:33 Trace: [Studio] [LowQuality, 24/48 AAC => 24/48] [STOPPED @ 0:00] Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA
11/08 12:54:33 Info: [audio/env] [zoneplayer] All streams were disposed
11/08 12:54:33 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [1110] {"status":"Ended"}
11/08 12:54:34 Trace: [Matrix Audio mini-i Pro 3 @ 192.168.1.207:36003] [raatclient] GOT [2680] {"status":"Success"}
11/08 12:54:34 Debug: [easyhttp] [2478] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack& returned after 249 ms, status code: 200
11/08 12:54:34 Debug: [easyhttp] [2477] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/events?format=msgpack& returned after 252 ms, status code: 200
11/08 12:54:34 Debug: [easyhttp] [2480] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack& returned after 236 ms, status code: 200
11/08 12:54:34 Debug: [easyhttp] [2479] POST to https://internetradio.roonlabs.net/2/api/reporting/internetradioplayback/plays?format=msgpack& returned after 253 ms, status code: 400
11/08 12:54:34 Info: sleep 570ms after flush
11/08 12:54:34 Info: [Studio] [zoneplayer] Playing: channel://icy%3a%2f%2fwfmt.streamguys1.com%2fmain-source
11/08 12:54:34 Trace: Got ICY Stream Title [Benjamin Britten - Gloriana - Julian Bream Consort - Julian Bream Edition Vol 6: The Julian Bream Consort - RCA]
11/08 12:54:36 Info: [Studio] [zoneplayer] Open Result (Playing):Result[Status=Success]
11/08 12:54:36 Info: [Studio] [zoneplayer] Aborting play because track changed
11/08 12:54:36 Warn: [streammediafile] error reading stream: Unable to read data from the transport connection: Software caused connection abort.
11/08 12:54:36 Info: [audio/env] [zoneplayer] All streams were disposed
11/08 12:54:43 Info: [stats] 24288mb Virtual, 2737mb Physical, 1360mb Managed, 436 Handles, 91 Threads
11/08 12:54:51 Debug: [easyhttp] [2481] POST to https://discovery.roonlabs.net/1/query returned after 216 ms, status code: 200
11/08 12:54:58 Info: [stats] 24280mb Virtual, 2737mb Physical, 1357mb Managed, 436 Handles, 93 Threads