Roon Core keeps on disconnecting from ROCK

Arthur_Gonzales · May 13, 2021, 4:07am

Core Machine

Roon ROCK on Intel NUC 10th Gen, i5, 16 GB or RAM, 256 GB M.2 SSD

Network Details

Asus ZenWifi XT8 mesh, Core is connected wired via ethernet to the main router. I have fiber internet at 500 Mbps.

Audio Devices

2 Macs wirelessly connected, 2 raspberry pis with ropieee wirelessly connected.

Description of Issue

Roon Core keeps losing connection. Streaming from both TIDAL and Qobuz stops randomly. Here’s part of the log:

05/13 03:01:46 Trace: [RPi4] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PLAYING @ 0:09/4:41] Sovay - Andrew Bird
05/13 03:01:46 Trace: [raatserver] [RaatServer RPi4 @ 192.168.50.7:33525] lost client connection. Retrying
05/13 03:01:46 Trace: [raatserver] [RaatServer RPi4 @ 192.168.50.7:33525] connecting (attempt 1)
05/13 03:01:46 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
05/13 03:01:46 Trace: [push] retrying connection in 40167ms
05/13 03:01:46 Info: [transport] destroyed zone RPi4 was playing? True
05/13 03:01:46 Trace: [zone RPi4] Suspend
05/13 03:01:46 Info: [zone RPi4] OnPlayFeedback Stopped
05/13 03:01:46 Info: [zone RPi4] Canceling Pending Sleep
05/13 03:01:46 Info: [zone RPi4] Canceling Pending Sleep
05/13 03:01:46 Debug: [raat/tcpaudiosource] disconnecting
05/13 03:01:46 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object.
05/13 03:01:46 Warn: [raat/tcpaudiosource] disconnecting + retrying
05/13 03:01:46 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
05/13 03:01:46 Trace: [RPi4] [zoneplayer/raat] Endpoint E30 State Changed: Playing => Prepared
05/13 03:01:46 Info: [audio/env] [zoneplayer] All streams were disposed
05/13 03:01:46 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
05/13 03:01:46 Info: sleep 40ms after flush
05/13 03:01:46 Error: getting null client....
05/13 03:01:47 Trace: [raatserver] [RaatServer Arthurs-iMac @ 192.168.50.28:9200] lost client connection. Retrying
05/13 03:01:47 Trace: [raatserver] [RaatServer Arthurs-iMac @ 192.168.50.28:9200] connecting (attempt 1)
05/13 03:01:48 Info: [transport/zonedisplay] Zone display unregistered: Chromecast
05/13 03:01:52 Info: [brokerserver] Client disconnected: 192.168.50.28:51251
05/13 03:01:53 Info: [brokerserver] Client connected: 192.168.50.28:51931
05/13 03:01:53 Trace: [raat] [sood] Refreshing device list
05/13 03:01:53 Trace: [raatserver] [sood] Refreshing device list
05/13 03:01:53 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=7b58997a-19f7-4a01-a777-5dbcd1f61bd8 BrokerName='Roon Optimized Core Kit' }
05/13 03:01:53 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800790 DisplayValue='1.8 (build 790) stable' Branch='stable' } }
05/13 03:01:53 Debug: [music/profilestats] Computed stats in 49ms, got 42 top albums, 45 top performers, 14 genres
05/13 03:01:53 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 211753934 bytes, 213054 ops in 43936 ms)
05/13 03:01:53 Trace: [radio/query] performing channel query
05/13 03:01:53 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
05/13 03:01:53 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 211753934 bytes, 213054 ops in 43939 ms)
05/13 03:01:53 Debug: [music/profilestats] Computed stats in 38ms, got 42 top albums, 45 top performers, 14 genres
05/13 03:01:53 Trace: [radio/query] performing channel query
05/13 03:01:53 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
05/13 03:01:53 Info: [transport] creating endpoint for cast device 23:1:629fce7d-e239-39dd-e29c-63b3f019577d
05/13 03:01:53 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
05/13 03:01:53 Trace: [transport/audeze] picking preset lowlatency 
05/13 03:01:53 Trace: [transport/audeze] picked null preset!
05/13 03:01:53 Info: [library/albumdetails] Created album details screen for LibraryAlbum[813359, Andrew Bird - The Mysterious Production of Eggs]
05/13 03:01:53 Info: [library/albumdetails] 79004d5730303030333731303831, 79004d5230303032373532333634, 79004d5230303034363938323838, 79004d5230303034363635363838, 3e012b3c7ef8831ccc47ae1c48c04a20c8b0, 3e01b1f26505dafe9e3caead52ba9a9a7e9c, 79004d5230303034373037343335, 3e01d33c62851177e747b6724fb9c6c024bf, 3e0125bac82e410ef84cbf31cce778edab85, 3e01913fef149479a544a55d60f512ed7641, 3e01a52531239352784c84b398aa8ab81ab1, a6003230313139343432, c80030363334373237303033393233, be003138363530393538, be003138363530393539, be003535383332, be003136333235353037
05/13 03:01:53 Trace: [music/query] performing album query
05/13 03:01:53 Trace: [music/query] query returned 6 Sooloos.Broker.Api.AlbumLite(s)
05/13 03:01:53 Trace: [music/query] performing album query
05/13 03:01:53 Trace: [music/query] query returned 0 Sooloos.Broker.Api.AlbumLite(s)

Arthur_Gonzales · May 13, 2021, 4:12am

Just happened again. Here’s the last 2 minutes of the log:

05/13 03:47:32 Info: [library/albumdetails] 79004d5730303030333731303831, 79004d5230303032373532333634, 79004d5230303034363938323838, 79004d5230303034363635363838, 3e012b3c7ef8831ccc47ae1c48c04a20c8b0, 3e01b1f26505dafe9e3caead52ba9a9a7e9c, 79004d5230303034373037343335, 3e01d33c62851177e747b6724fb9c6c024bf, 3e0125bac82e410ef84cbf31cce778edab85, 3e01913fef149479a544a55d60f512ed7641, 3e01a52531239352784c84b398aa8ab81ab1, a6003230313139343432, c80030363334373237303033393233, be003138363530393538, be003138363530393539, be003535383332, be003136333235353037
05/13 03:47:32 Trace: [music/query] performing album query
05/13 03:47:32 Debug: GET /image/rvaaaaaa.1024.jpg
05/13 03:47:32 Trace: [music/query] query returned 6 Sooloos.Broker.Api.AlbumLite(s)
05/13 03:47:32 Trace: [music/query] performing album query
05/13 03:47:32 Trace: [music/query] query returned 0 Sooloos.Broker.Api.AlbumLite(s)
05/13 03:47:32 Debug: [easyhttp] [1889] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 579 ms, status code: 200
05/13 03:47:32 Debug: [easyhttp] [1887] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 586 ms, status code: 200
05/13 03:47:32 Info: [library/albumdetails] found 2 streaming service alternates
05/13 03:47:32 Debug: [easyhttp] [1895] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/albums/morefromcollaborators?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 567 ms, status code: 404
05/13 03:47:32 Debug: [easyhttp] [1896] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/similar/albums?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 591 ms, status code: 404
05/13 03:47:32 Debug: [easyhttp] [1891] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 683 ms, status code: 200
05/13 03:47:32 Debug: [easyhttp] [1899] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/recommended/albums?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 738 ms, status code: 200
05/13 03:47:33 Debug: [easyhttp] [1892] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-sg,qobuz-us returned after 837 ms, status code: 200
05/13 03:47:33 Debug: [easyhttp] [1893] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/albums/new?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 993 ms, status code: 200
05/13 03:47:33 Debug: [easyhttp] [1890] GET to https://www.qobuz.com/api.json/0.2/album/get?album_id=0634727003923&limit=0&offset=0&request_sig=c9e3a4e5dccd63144dd2ab574bbdfce1&request_ts=1620877652 returned after 1117 ms, status code: 200
05/13 03:47:33 Debug: [easyhttp] [1901] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 495 ms, status code: 200
05/13 03:47:34 Debug: [easyhttp] [1900] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 859 ms, status code: 200
05/13 03:47:34 Debug: [easyhttp] [1888] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 2326 ms, status code: 200
05/13 03:47:34 Debug: [easyhttp] [1897] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/performers/similar?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 2431 ms, status code: 200
05/13 03:47:38 Trace: [music/query] performing tag query
05/13 03:47:38 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 03:47:38 Trace: [music/query] performing tag query
05/13 03:47:38 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 03:47:38 Trace: [music/query] performing tag query
05/13 03:47:38 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 03:47:38 Trace: [music/query] performing tag query
05/13 03:47:38 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 03:47:38 Trace: [music/query] performing album query
05/13 03:47:38 Trace: [music/query] query returned 1519 Sooloos.Broker.Api.AlbumLite(s)
05/13 03:47:39 Debug: [easyhttp] [1894] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/timeline/albums?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 7104 ms, status code: 200
05/13 03:47:39 Debug: [easyhttp] [1898] GET to https://discover.roonlabs.net/1/albums/121:0:MW0000371081/albums/morefromgenre?profileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 7112 ms, status code: 404
05/13 03:47:39 Info: [stats] 27454mb Virtual, 942mb Physical, 324mb Managed, 302 Handles, 89 Threads
05/13 03:47:42 Info: [library] saved recent ProfileId=3b291f34-c94c-4c52-970a-63ed8a3d30cf Time=05/13/2021 03:47:42 DataType=album Type=long_nav MetadataId=3693433 ContentId=3693433 LibraryId=813359
05/13 03:47:43 Debug: [easyhttp] [1902] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 5325 ms, status code: 200
05/13 03:47:54 Info: [stats] 27446mb Virtual, 942mb Physical, 334mb Managed, 300 Handles, 84 Threads
05/13 03:48:09 Info: [stats] 27422mb Virtual, 934mb Physical, 324mb Managed, 300 Handles, 84 Threads
05/13 03:48:21 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
05/13 03:48:21 Trace: [push] retrying connection in 19023ms
05/13 03:48:24 Info: [stats] 27382mb Virtual, 934mb Physical, 329mb Managed, 299 Handles, 78 Threads
05/13 03:48:31 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
05/13 03:48:31 Trace: [push] connecting to 104.196.205.85:9200
05/13 03:48:31 Trace: [push] connected
05/13 03:48:38 Trace: [musicpowerstate] music has not been playing for 5 minutes, allowing idle sleep
05/13 03:48:39 Info: [stats] 27446mb Virtual, 934mb Physical, 339mb Managed, 300 Handles, 88 Threads
05/13 03:48:54 Info: [stats] 27446mb Virtual, 928mb Physical, 323mb Managed, 300 Handles, 88 Threads
05/13 03:49:09 Info: [stats] 27422mb Virtual, 928mb Physical, 331mb Managed, 300 Handles, 84 Threads
05/13 03:49:24 Info: [stats] 27390mb Virtual, 929mb Physical, 339mb Managed, 299 Handles, 77 Threads
05/13 03:49:39 Info: [stats] 27398mb Virtual, 926mb Physical, 329mb Managed, 299 Handles, 82 Threads
05/13 03:49:54 Info: [stats] 27438mb Virtual, 926mb Physical, 340mb Managed, 299 Handles, 87 Threads
05/13 03:50:09 Info: [stats] 27398mb Virtual, 923mb Physical, 329mb Managed, 299 Handles, 81 Threads
05/13 03:50:21 Trace: [broker/accounts] [heartbeat] now=05/13/2021 03:50:21 nextauthrefresh=05/13/2021 04:20:38 nextmachineallocate=05/13/2021 07:20:21
05/13 03:50:24 Info: [stats] 27390mb Virtual, 923mb Physical, 333mb Managed, 299 Handles, 77 Threads
05/13 03:50:39 Info: [stats] 27398mb Virtual, 923mb Physical, 341mb Managed, 299 Handles, 82 Threads
05/13 03:50:54 Info: [stats] 27414mb Virtual, 922mb Physical, 334mb Managed, 299 Handles, 84 Threads
05/13 03:51:09 Info: [stats] 27414mb Virtual, 922mb Physical, 343mb Managed, 299 Handles, 83 Threads
05/13 03:51:24 Info: [stats] 27390mb Virtual, 922mb Physical, 346mb Managed, 299 Handles, 77 Threads
05/13 03:51:39 Info: [stats] 27406mb Virtual, 920mb Physical, 338mb Managed, 299 Handles, 83 Threads
05/13 03:51:54 Info: [stats] 27422mb Virtual, 920mb Physical, 349mb Managed, 299 Handles, 85 Threads
05/13 03:52:09 Info: [stats] 27406mb Virtual, 920mb Physical, 334mb Managed, 299 Handles, 82 Threads
05/13 03:52:24 Info: [stats] 27406mb Virtual, 921mb Physical, 344mb Managed, 299 Handles, 79 Threads
05/13 03:52:39 Info: [stats] 27398mb Virtual, 921mb Physical, 352mb Managed, 299 Handles, 82 Threads
05/13 03:52:54 Info: [stats] 27422mb Virtual, 921mb Physical, 335mb Managed, 299 Handles, 85 Threads
05/13 03:53:09 Info: [stats] 27414mb Virtual, 921mb Physical, 343mb Managed, 299 Handles, 83 Threads
05/13 03:53:24 Info: [stats] 27414mb Virtual, 921mb Physical, 347mb Managed, 299 Handles, 80 Threads
05/13 03:53:39 Info: [stats] 27406mb Virtual, 919mb Physical, 328mb Managed, 299 Handles, 83 Threads
05/13 03:53:54 Info: [stats] 27430mb Virtual, 919mb Physical, 336mb Managed, 299 Handles, 86 Threads
05/13 03:54:09 Info: [stats] 27398mb Virtual, 919mb Physical, 344mb Managed, 299 Handles, 82 Threads
05/13 03:54:24 Info: [stats] 27398mb Virtual, 919mb Physical, 348mb Managed, 299 Handles, 79 Threads
05/13 03:54:39 Info: [stats] 27414mb Virtual, 918mb Physical, 334mb Managed, 299 Handles, 84 Threads
05/13 03:54:54 Info: [stats] 27430mb Virtual, 918mb Physical, 343mb Managed, 299 Handles, 86 Threads
05/13 03:55:09 Info: [stats] 27414mb Virtual, 918mb Physical, 330mb Managed, 299 Handles, 82 Threads
05/13 03:55:21 Trace: [broker/accounts] [heartbeat] now=05/13/2021 03:55:21 nextauthrefresh=05/13/2021 04:20:38 nextmachineallocate=05/13/2021 07:20:21
05/13 03:55:24 Info: [stats] 27406mb Virtual, 919mb Physical, 338mb Managed, 299 Handles, 79 Threads
05/13 03:55:39 Info: [stats] 27414mb Virtual, 919mb Physical, 346mb Managed, 299 Handles, 84 Threads
05/13 03:55:54 Info: [stats] 27422mb Virtual, 918mb Physical, 337mb Managed, 299 Handles, 85 Threads
05/13 03:56:09 Info: [stats] 27422mb Virtual, 918mb Physical, 345mb Managed, 299 Handles, 85 Threads
05/13 03:56:24 Info: [stats] 27390mb Virtual, 918mb Physical, 348mb Managed, 299 Handles, 77 Threads
05/13 03:56:39 Info: [stats] 27414mb Virtual, 918mb Physical, 356mb Managed, 299 Handles, 84 Threads
05/13 03:56:54 Info: [stats] 27422mb Virtual, 918mb Physical, 339mb Managed, 299 Handles, 85 Threads
05/13 03:57:09 Info: [stats] 27422mb Virtual, 918mb Physical, 345mb Managed, 299 Handles, 83 Threads
05/13 03:57:24 Info: [stats] 27390mb Virtual, 918mb Physical, 348mb Managed, 299 Handles, 77 Threads
05/13 03:57:39 Info: [stats] 27406mb Virtual, 918mb Physical, 336mb Managed, 299 Handles, 83 Threads
05/13 03:57:54 Info: [stats] 27414mb Virtual, 918mb Physical, 345mb Managed, 299 Handles, 84 Threads
05/13 03:58:09 Info: [stats] 27414mb Virtual, 918mb Physical, 332mb Managed, 299 Handles, 81 Threads
05/13 03:58:24 Info: [stats] 27398mb Virtual, 920mb Physical, 342mb Managed, 299 Handles, 80 Threads
05/13 03:58:39 Info: [stats] 27430mb Virtual, 920mb Physical, 349mb Managed, 299 Handles, 86 Threads
05/13 03:58:55 Info: [stats] 27430mb Virtual, 920mb Physical, 358mb Managed, 299 Handles, 86 Threads
05/13 03:59:10 Info: [stats] 27390mb Virtual, 920mb Physical, 343mb Managed, 299 Handles, 81 Threads
05/13 03:59:25 Info: [stats] 27390mb Virtual, 920mb Physical, 346mb Managed, 299 Handles, 78 Threads
05/13 03:59:40 Info: [stats] 27422mb Virtual, 920mb Physical, 354mb Managed, 299 Handles, 85 Threads
05/13 03:59:55 Info: [stats] 27422mb Virtual, 920mb Physical, 342mb Managed, 299 Handles, 85 Threads
05/13 04:00:10 Info: [stats] 27398mb Virtual, 920mb Physical, 353mb Managed, 299 Handles, 80 Threads
05/13 04:00:21 Trace: [broker/accounts] [heartbeat] now=05/13/2021 04:00:21 nextauthrefresh=05/13/2021 04:20:38 nextmachineallocate=05/13/2021 07:20:21
05/13 04:00:25 Info: [stats] 27398mb Virtual, 920mb Physical, 356mb Managed, 299 Handles, 78 Threads
05/13 04:00:40 Info: [stats] 27414mb Virtual, 920mb Physical, 342mb Managed, 299 Handles, 84 Threads
05/13 04:00:55 Info: [stats] 27414mb Virtual, 920mb Physical, 351mb Managed, 299 Handles, 84 Threads
05/13 04:01:10 Info: [stats] 27414mb Virtual, 920mb Physical, 359mb Managed, 299 Handles, 84 Threads
05/13 04:01:25 Info: [stats] 27398mb Virtual, 922mb Physical, 343mb Managed, 299 Handles, 78 Threads
05/13 04:01:40 Info: [stats] 27406mb Virtual, 922mb Physical, 350mb Managed, 299 Handles, 83 Threads
05/13 04:01:55 Info: [stats] 27430mb Virtual, 922mb Physical, 343mb Managed, 299 Handles, 86 Threads
05/13 04:02:10 Info: [stats] 27414mb Virtual, 922mb Physical, 350mb Managed, 299 Handles, 83 Threads
05/13 04:02:25 Info: [stats] 27390mb Virtual, 922mb Physical, 354mb Managed, 299 Handles, 77 Threads
05/13 04:02:40 Info: [stats] 27422mb Virtual, 922mb Physical, 362mb Managed, 299 Handles, 85 Threads
05/13 04:02:55 Info: [stats] 27422mb Virtual, 922mb Physical, 348mb Managed, 299 Handles, 85 Threads
05/13 04:03:10 Info: [stats] 27422mb Virtual, 922mb Physical, 355mb Managed, 299 Handles, 84 Threads
05/13 04:03:25 Info: [stats] 27398mb Virtual, 922mb Physical, 359mb Managed, 299 Handles, 78 Threads
05/13 04:03:40 Info: [stats] 27398mb Virtual, 922mb Physical, 346mb Managed, 299 Handles, 82 Threads
05/13 04:03:55 Info: [stats] 27422mb Virtual, 922mb Physical, 354mb Managed, 299 Handles, 85 Threads
05/13 04:04:10 Info: [stats] 27422mb Virtual, 922mb Physical, 342mb Managed, 299 Handles, 83 Threads
05/13 04:04:25 Info: [stats] 27398mb Virtual, 923mb Physical, 351mb Managed, 299 Handles, 78 Threads
05/13 04:04:40 Info: [stats] 27406mb Virtual, 923mb Physical, 359mb Managed, 299 Handles, 83 Threads
05/13 04:04:55 Info: [stats] 27414mb Virtual, 923mb Physical, 368mb Managed, 299 Handles, 84 Threads
05/13 04:05:10 Info: [stats] 27414mb Virtual, 923mb Physical, 352mb Managed, 301 Handles, 83 Threads
05/13 04:05:21 Trace: [broker/accounts] [heartbeat] now=05/13/2021 04:05:21 nextauthrefresh=05/13/2021 04:20:38 nextmachineallocate=05/13/2021 07:20:21
05/13 04:05:25 Info: [stats] 27382mb Virtual, 923mb Physical, 357mb Managed, 299 Handles, 76 Threads
05/13 04:05:40 Info: [stats] 27422mb Virtual, 923mb Physical, 366mb Managed, 299 Handles, 85 Threads
05/13 04:05:55 Info: [stats] 27422mb Virtual, 923mb Physical, 348mb Managed, 299 Handles, 85 Threads
05/13 04:06:10 Info: [stats] 27422mb Virtual, 923mb Physical, 358mb Managed, 299 Handles, 82 Threads
05/13 04:06:25 Info: [stats] 27390mb Virtual, 923mb Physical, 361mb Managed, 299 Handles, 77 Threads
05/13 04:06:40 Info: [stats] 27406mb Virtual, 923mb Physical, 348mb Managed, 299 Handles, 83 Threads
05/13 04:06:55 Info: [stats] 27422mb Virtual, 923mb Physical, 358mb Managed, 299 Handles, 85 Threads
05/13 04:07:10 Info: [stats] 27422mb Virtual, 923mb Physical, 364mb Managed, 299 Handles, 85 Threads
05/13 04:07:25 Info: [stats] 27398mb Virtual, 926mb Physical, 371mb Managed, 299 Handles, 78 Threads
05/13 04:07:40 Info: [stats] 27430mb Virtual, 926mb Physical, 355mb Managed, 299 Handles, 86 Threads
05/13 04:07:55 Info: [stats] 27438mb Virtual, 926mb Physical, 359mb Managed, 299 Handles, 84 Threads
05/13 04:08:10 Info: [stats] 27406mb Virtual, 926mb Physical, 371mb Managed, 299 Handles, 82 Threads
05/13 04:08:25 Info: [stats] 27390mb Virtual, 926mb Physical, 351mb Managed, 299 Handles, 77 Threads
05/13 04:08:26 Info: [transport] destroyed zone NAD C 338 was playing? False
05/13 04:08:26 Trace: [zone NAD C 338] Suspend
05/13 04:08:26 Info: [zone NAD C 338] Canceling Pending Sleep
05/13 04:08:26 Info: [zone NAD C 338] Canceling Pending Sleep
05/13 04:08:27 Trace: [raatserver] [RaatServer RPi4 @ 192.168.50.7:33525] lost client connection. Retrying
05/13 04:08:27 Trace: [raatserver] [RaatServer RPi4 @ 192.168.50.7:33525] connecting (attempt 1)
05/13 04:08:27 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
05/13 04:08:27 Trace: [push] retrying connection in 96157ms
05/13 04:08:27 Info: [transport] destroyed zone RPi4 was playing? False
05/13 04:08:27 Trace: [zone RPi4] Suspend
05/13 04:08:27 Info: [zone RPi4] Canceling Pending Sleep
05/13 04:08:27 Info: [zone RPi4] Canceling Pending Sleep
05/13 04:08:28 Trace: [raatserver] [RaatServer Arthurs-iMac @ 192.168.50.28:9200] lost client connection. Retrying
05/13 04:08:28 Trace: [raatserver] [RaatServer Arthurs-iMac @ 192.168.50.28:9200] connecting (attempt 1)
05/13 04:08:29 Info: [transport/zonedisplay] Zone display unregistered: Chromecast
05/13 04:08:29 Info: [brokerserver] Client disconnected: 192.168.50.28:52606
05/13 04:08:31 Info: [brokerserver] Client connected: 192.168.50.28:53055
05/13 04:08:31 Trace: [raat] [sood] Refreshing device list
05/13 04:08:31 Trace: [raatserver] [sood] Refreshing device list
05/13 04:08:31 Trace: SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=7b58997a-19f7-4a01-a777-5dbcd1f61bd8 BrokerName='Roon Optimized Core Kit' }
05/13 04:08:31 Trace: SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=100800790 DisplayValue='1.8 (build 790) stable' Branch='stable' } }
05/13 04:08:31 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 211753934 bytes, 213054 ops in 43992 ms)
05/13 04:08:31 Debug: [music/profilestats] Computed stats in 60ms, got 42 top albums, 45 top performers, 14 genres
05/13 04:08:31 Trace: [dbperf] flush 0 bytes, 0 ops in 5 ms (cumulative 211753934 bytes, 213054 ops in 43997 ms)
05/13 04:08:31 Trace: [radio/query] performing channel query
05/13 04:08:31 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
05/13 04:08:31 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 211753934 bytes, 213054 ops in 44000 ms)
05/13 04:08:31 Debug: [music/profilestats] Computed stats in 39ms, got 42 top albums, 45 top performers, 14 genres
05/13 04:08:31 Trace: [radio/query] performing channel query
05/13 04:08:31 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
05/13 04:08:31 Trace: [music/query] performing album query
05/13 04:08:31 Trace: [music/query] query returned 1519 Sooloos.Broker.Api.AlbumLite(s)
05/13 04:08:31 Trace: [music/query] performing tag query
05/13 04:08:31 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 04:08:31 Trace: [music/query] performing tag query
05/13 04:08:31 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 04:08:31 Trace: [music/query] performing tag query
05/13 04:08:31 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 04:08:31 Trace: [music/query] performing tag query
05/13 04:08:31 Trace: [music/query] query returned 0 Sooloos.Broker.Api.Tag(s)
05/13 04:08:31 Info: [transport] creating endpoint for cast device 23:1:629fce7d-e239-39dd-e29c-63b3f019577d
05/13 04:08:31 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
05/13 04:08:31 Trace: [transport/audeze] picking preset lowlatency 
05/13 04:08:31 Trace: [transport/audeze] picked null preset!
05/13 04:08:31 Trace: [zone NAD C 338] Loading
05/13 04:08:31 Trace: [zone NAD C 338] Suspend
05/13 04:08:31 Info: [zone NAD C 338] Canceling Pending Sleep
05/13 04:08:31 Info: [transport] created zone NAD C 338
05/13 04:08:31 Info: [transport]     with endpoint NAD Amplifier C338 (23:1:629fce7d-e239-39dd-e29c-63b3f019577d) [IsAvailable=True]
05/13 04:08:31 Trace: [NAD C 338] [Inactive] [STOPPED @ 0:00] 
05/13 04:08:31 Trace: [zone NAD C 338] Loaded Queue=119 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False
05/13 04:08:31 Trace: [NAD C 338] [Inactive] [PAUSED @ 1:08/4:23] Cry Like a Ghost - Passion Pit
05/13 04:08:31 Trace: [raatserver] [RaatServer Arthurs-iMac @ 192.168.50.28:9200] connected
05/13 04:08:31 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
05/13 04:08:31 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "BuiltInSpeakerDevice", "type": "coreaudio", "name": "iMac Speakers", "vendor": "Apple Inc.", "config": {"volume": {"type": "coreaudio", "device": "BuiltInSpeakerDevice"}, "output": {"type": "coreaudio", "device": "BuiltInSpeakerDevice", "name": "iMac Speakers"}, "unique_id": "9715793f-70c6-ee6c-6708-b1f592441840", "external_config": {}}}, {"device_id": "default", "type": "coreaudio", "is_system_output": true, "name": "System Output", "config": {"volume": {"type": "coreaudio", "device": "default"}, "output": {"type": "coreaudio", "device": "default", "name": "System Output"}, "unique_id": "74960038-dcff-7ff4-07d3-9cd26fa3d678", "external_config": {}}}]}
05/13 04:08:31 Info: [raatserver] GOT DEVICE bf665568-9aa2-4a30-ace8-743a6886fd42::BuiltInSpeakerDevice Type=coreaudio Name=iMac Speakers Vendor=Apple Inc.
05/13 04:08:31 Info: [raatserver] GOT DEVICE bf665568-9aa2-4a30-ace8-743a6886fd42::default Type=coreaudio Name=System Output 
05/13 04:08:31 Info: [transport/zonedisplay] Zone display registered: Chromecast
05/13 04:08:32 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=SG&offset=0&limit=30 => Success
05/13 04:08:32 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=SG&offset=0&limit=30 => Success
05/13 04:08:32 Debug: [easyhttp] [1916] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/performers/recommended?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 557 ms, status code: 404
05/13 04:08:32 Debug: [easyhttp] [1909] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/performers/recommended?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 671 ms, status code: 404
05/13 04:08:32 Debug: [easyhttp] [1904] POST to https://discovery.roonlabs.net/1/query returned after 912 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1918] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/collaboration?c=tidal-sg,qobuz-us returned after 732 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1913] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/albums/newv3?c=tidal-sg,qobuz-us returned after 775 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1908] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/genres?c=tidal-sg,qobuz-us returned after 894 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1906] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/albums/newv3?c=tidal-sg,qobuz-us returned after 962 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1905] GET to https://messaging.roonlabs.net/1/api/messages/c1788b55-d791-4c57-bf61-e6e92ddd1817 returned after 1027 ms, status code: 200
05/13 04:08:32 Debug: [easyhttp] [1907] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 1156 ms, status code: 304
05/13 04:08:32 Debug: [easyhttp] [1914] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 1107 ms, status code: 304
05/13 04:08:33 Debug: [easyhttp] [1919] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=289c3acd43fe082aaf90a2377cc8b88b&request_ts=1620878911&&type=editor-picks returned after 1548 ms, status code: 200
05/13 04:08:33 Debug: [easyhttp] [1912] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=289c3acd43fe082aaf90a2377cc8b88b&request_ts=1620878911&&type=editor-picks returned after 1602 ms, status code: 200
05/13 04:08:34 Debug: [easyhttp] [1910] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/coveredperformer?c=tidal-sg,qobuz-us returned after 2400 ms, status code: 200
05/13 04:08:34 Trace: [raatserver] [RaatServer RPi4 @ 192.168.50.7:33525] connected
05/13 04:08:34 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
05/13 04:08:34 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"vendor": "Topping", "device_id": "hw:CARD=E30,DEV=0", "config": {"external_config": {}, "output": {"device": "hw:CARD=E30,DEV=0", "type": "alsa", "force_max_volume": false, "name": "E30", "dsd_mode": "native"}, "unique_id": "9b337932-0467-1509-adfb-75afa4fb6652", "volume": {"device": "hw:CARD=E30,DEV=0", "type": "alsa", "exclusive_mode": null}}, "usb_id": "152a:8750", "type": "alsa", "name": "E30", "discovery_data": {"version": "N/A", "raat_version": "1.1.38", "tcp_port": 40321, "model": "N/A", "unique_id": "9b337932-0467-1509-adfb-75afa4fb6652", "protocol_version": "3", "vendor": "N/A"}}]}
05/13 04:08:34 Info: [raatserver] GOT DEVICE 89ce01e6-6700-43de-9b17-9add776de229::hw:CARD=E30,DEV=0 Type=alsa Name=E30 Vendor=ToppingUsbId=152a:8750
05/13 04:08:34 Trace: [rnet/RnetJsonClient] SENT {"request":"enable_device","device_id":"hw:CARD=E30,DEV=0","subscription_id":"21"}
05/13 04:08:34 Info: [raatserver] [E30] connecting (attempt 1)
05/13 04:08:34 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success"}
05/13 04:08:34 Trace: [E30] [raatclient] Initializing RAAT session
05/13 04:08:34 Trace: [E30] [raatclient] Loading script raat.dat:base.lua as module base [1088 chars]
05/13 04:08:34 Trace: [E30] [raatclient] Loading script raat.dat:dkjson.lua as module dkjson [22416 chars]
05/13 04:08:34 Trace: [E30] [raatclient] Loading script raat.dat:protocol.lua as module protocol [2045 chars]
05/13 04:08:34 Trace: [E30] [raatclient] Loading script raat.dat:roon_tcp.lua [43475 chars]
05/13 04:08:34 Trace: [E30] [raatclient] RAAT Session initialized in 37ms
05/13 04:08:34 Trace: [E30] [raatclient] SENT [2]{"request":"info"}
05/13 04:08:34 Trace: [E30] [raatclient] SENT [3]{"request":"set_client_type","client_type":"Roon"}
05/13 04:08:34 Trace: [E30] [raatclient] GOT [2] {"info":{"unique_id":"9b337932-0467-1509-adfb-75afa4fb6652"},"flags":{"has_write_chmap":true},"status":"Success","source_selection":{"is_supported":false,"info":[]},"output":{"supported_formats":"88200/24/1|2,48000/16/1|2,705600/16/1|2,48000/32/1|2,44100/16/1|2,352800/24/1|2,96000/16/1|2,192000/32/1|2,88200/16/1|2,384000/16/1|2,44100/32/1|2,11289600/1/1|2,176400/24/1|2,192000/24/1|2,192000/16/1|2,352800/16/1|2,44100/24/1|2,705600/32/1|2,768000/24/1|2,768000/16/1|2,384000/24/1|2,88200/32/1|2,5644800/1/1|2,768000/32/1|2,22579200/1/1|2,176400/16/1|2,352800/32/1|2,176400/32/1|2,2822400/1/1|2,48000/24/1|2,384000/32/1|2,96000/32/1|2,705600/24/1|2,96000/24/1|2","info":{"config":{"force_max_volume":false,"name":"E30","dsd_mode":"native","type":"alsa","device":"hw:CARD=E30,DEV=0"},"alsa_device":{"driver":"USB-Audio","components":"E30","mixername":"USB Mixer","id":"E30","longname":"Topping E30 at usb-0000:01:00.0-1.2, high speed","name":"E30","usbid":"152a:8750"}}},"platform":{"uname":{"release":"5.4.83-5-SPCKFSH-v7l+","machine":"armv7l","nodename":"RPi4","sysname":"Linux","version":"#1 SMP PREEMPT Sun Mar 28 21:20:33 CEST 2021"},"os":"linux"},"transport":{"info":[],"is_supported":false},"volume":{"is_supported":true,"info":{"config":{"type":"alsa","device":"hw:CARD=E30,DEV=0"}}}}
05/13 04:08:34 Trace: [raatserver] [E30] connected
05/13 04:08:34 Info: [transport] creating endpoint for raat device 9b337932-0467-1509-adfb-75afa4fb6652
05/13 04:08:34 Trace: [E30] [raatclient] SENT [4]{"request":"subscribe_transport"}
05/13 04:08:34 Trace: [E30] [raatclient] SENT [5]{"request":"subscribe_controls","controller_id":"7b58997a-19f7-4a01-a777-5dbcd1f61bd8"}
05/13 04:08:34 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"bs2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
05/13 04:08:34 Trace: [transport/audeze] picking preset lowlatency 
05/13 04:08:34 Trace: [transport/audeze] picked null preset!
05/13 04:08:34 Trace: [E30] [raatclient] GOT [3] {"status":"Success"}
05/13 04:08:34 Trace: [E30] [raatclient] GOT [5] {"action":"Changed","status":"Success","controls":{"volume":{"max":100.0,"min":0.0,"mute":false,"step":1.0,"value":100,"type":"number","info":{"config":{"type":"alsa","device":"hw:CARD=E30,DEV=0"}}}}}
05/13 04:08:34 Trace: [zone RPi4] Loading
05/13 04:08:34 Trace: [zone RPi4] Suspend
05/13 04:08:34 Info: [zone RPi4] Canceling Pending Sleep
05/13 04:08:34 Trace: [RPi4] [zoneplayer/raat] Endpoint E30 Initial State: Idle
05/13 04:08:34 Info: [transport] created zone RPi4
05/13 04:08:34 Info: [transport]     with endpoint E30 (23:1:9b337932-0467-1509-adfb-75afa4fb6652) [IsAvailable=True]
05/13 04:08:34 Trace: [RPi4] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [STOPPED @ 0:00] 
05/13 04:08:34 Trace: [zone RPi4] Loaded Queue=273 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False
05/13 04:08:34 Trace: [RPi4] [Lossless, 16/44 TIDAL FLAC => 16/44] [100% buf] [PAUSED @ 0:19/4:21] MX Missiles - Andrew Bird
05/13 04:08:34 Info: [library/albumdetails] Created album details screen for LibraryAlbum[813359, Andrew Bird - The Mysterious Production of Eggs]
05/13 04:08:34 Info: [library/albumdetails] 79004d5730303030333731303831, 79004d5230303032373532333634, 79004d5230303034363938323838, 79004d5230303034363635363838, 3e012b3c7ef8831ccc47ae1c48c04a20c8b0, 3e01b1f26505dafe9e3caead52ba9a9a7e9c, 79004d5230303034373037343335, 3e01d33c62851177e747b6724fb9c6c024bf, 3e0125bac82e410ef84cbf31cce778edab85, 3e01913fef149479a544a55d60f512ed7641, 3e01a52531239352784c84b398aa8ab81ab1, a6003230313139343432, c80030363334373237303033393233, be003138363530393538, be003138363530393539, be003535383332, be003136333235353037
05/13 04:08:34 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 211753934 bytes, 213054 ops in 44002 ms)
05/13 04:08:35 Debug: [easyhttp] [1921] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 551 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1920] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 557 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1925] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 570 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1923] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 581 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1926] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 590 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1924] POST to https://metadata.roonlabs.net/1/performers/translate?c=tidal-sg,qobuz-us returned after 730 ms, status code: 200
05/13 04:08:35 Debug: [easyhttp] [1922] POST to https://metadata.roonlabs.net/1/albums/translate?c=tidal-sg,qobuz-us returned after 754 ms, status code: 200
05/13 04:08:35 Info: [library/albumdetails] found 2 streaming service alternates
05/13 04:08:37 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
05/13 04:08:37 Trace: [push] connecting to 104.196.205.85:9200
05/13 04:08:37 Trace: [push] connected
05/13 04:08:40 Info: [stats] 27542mb Virtual, 965mb Physical, 427mb Managed, 303 Handles, 100 Threads
05/13 04:08:40 Debug: [easyhttp] [1927] POST to https://browse.roonlabs.net/1/works/trackCounts?c=tidal-sg,qobuz-us returned after 6484 ms, status code: 200
05/13 04:08:45 Debug: [easyhttp] [1911] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/collaboration?c=tidal-sg,qobuz-us returned after 13847 ms, status code: 200
05/13 04:08:45 Debug: [easyhttp] [1915] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/genres?c=tidal-sg,qobuz-us returned after 13836 ms, status code: 200
05/13 04:08:45 Debug: [easyhttp] [1917] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/coveredperformer?c=tidal-sg,qobuz-us returned after 13898 ms, status code: 200
05/13 04:08:45 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 211753934 bytes, 213054 ops in 44003 ms)
05/13 04:08:55 Info: [stats] 27438mb Virtual, 976mb Physical, 369mb Managed, 299 Handles, 83 Threads```

Arthur_Gonzales · May 14, 2021, 2:30am

Hi @kevin , thanks for touching up the logs. Any update on this? I’m still getting connection drops from my core. Thanks.

Arthur_Gonzales · May 14, 2021, 3:54am

Hi @beka , please include me to the list of those who are having connection issues with Roon Core. Thanks so much.

beka · May 14, 2021, 6:26pm

Hey @Arthur_Gonzales,

Thanks so much for following up on your post and sorry about the trouble! We appreciate all the details, including the logs.

I’ve moved your thread into our technical team’s queue, so they can start helping troubleshoot this.

Thanks in advance for your patience

Arthur_Gonzales · May 14, 2021, 11:42pm

Thank you for your reply, @beka . Let me know if I need to include other information so that we can try to fix this.

Phil_Kemp · May 15, 2021, 12:00am

I too am getting disconnections from my roon server. Is there any update on this issue?

Thanks
Phil

Arthur_Gonzales · May 15, 2021, 5:46am

Hi @beka , it happened again. Many times actually. I was moving from room to room and was transferring playback. But several tracks skipped. Here’s the link to the log file. Thanks.

beka · May 18, 2021, 6:35pm

Hey @Arthur_Gonzales,

Thanks so much for your patience and for the logs! I’ve notified our team.

Please, bear with us just a little bit longer

Arthur_Gonzales · May 19, 2021, 5:53am

Thanks, @beka . Looking forward to solving this with you.

Arthur_Gonzales · June 4, 2021, 8:51am

Hi @beka Any update on this? Also, how long exactly is your queue? And how far down the list am I? Thanks!

dylan · June 4, 2021, 5:37pm

Hi Arthur,

Sorry for the continued delay here. I think this is an issue that we are working on resolving soon — I’ve enabled diagnostics on your ROCK so we can check for sure. I’ll follow up ASAP when I have analyzed the diagnostics report.

Arthur_Gonzales · June 5, 2021, 1:30am

Hi Dylan,

Thanks for the revert. I have lost the connection to the core a few times yesterday. I don’t know if it’s just the remotes (iPhone 8, iPad Air, MacBook Pro) which lost connection or the ROCK itself lost connection. Which brings me to a caveat about the diagnostics report you might see. I haven’t been playing that much using Roon. Maybe a couple of hours everyday, just to test if it’s been fixed. I have to say that for those two hours I haven’t had any drops or skips (to the endpoint) ever since the latest update (I got a lot of disconnections and crashes with the iOS remotes though). So the data available might not be representative of my situation.

I’m sorry to say that I have been using and enjoying Volumio this past month. It has its quirks, but they are all predictable and reproducible (so far) and I have not had disconnections, skips, or drops during playback. The UI is not as refined as Roon, very far from it. But at least it plays music without skipping. I’ll play music using Roon exclusively for now so that we can find the problem.

But I really like Roon. I’ve spent a lot optimizing my home network to cater to Roon, more than what my wife would have allowed if she knew the prices. That’s how much I like it! I was ready to shell out for the lifetime subscription but then this happened. But my problems just keep on reappearing–not gapless, skips, drops, disconnection to the Core! So I’m going to do monthly for now. So please help me understand the problem and let’s find a DEFINITE solution. I promise you, if you take care of me, I’ll be on board.

Arthur_Gonzales · June 6, 2021, 3:07am

Hi @dylan, I know it’s the weekend and I hope you’re enjoying yours.
I just wanted to report before I forget that after about 24 hours, I have only gotten one skip from a Tidal track Steve Roach’s Structures From Silence, which played only up to 10% before skipping to the next Radio track. It’s better than before when I get skips every 10 tracks or so. Below is the relevant log file section:

06/06 02:34:26 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-0.9213549999999997 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=32 Quality=Lossless
    Raat Device=E30
    Output OutputType=Local_Alsa Quality=Lossless SubType= Model=E30
------------------------------------------------------------
06/06 02:34:26 Trace: [E30] [raatclient] GOT [8] {"time":587537760,"status":"Success"}
06/06 02:34:28 Info: [stats] 30101mb Virtual, 2112mb Physical, 569mb Managed, 331 Handles, 97 Threads
06/06 02:34:30 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:04/28:49] Structures From Silence - Steve Roach
06/06 02:34:34 Debug: [easyhttp] [14969] POST to https://swim.roonlabs.net/1/session/1c9921c85ec543f5b89c08f12f2b7cca/ping returned after 372 ms, status code: 200
06/06 02:34:35 Trace: [streamingmediafile] finished caching http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKRInYmYyMTIyMDBlOTUxZmY0ZWEzM2ZjZGU2M2FkNjE5YzZfNjEubXA0/0.flac
06/06 02:34:36 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:09/28:49] Structures From Silence - Steve Roach
06/06 02:34:37 Trace: [streamingmediafile] finished caching http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKRInZDY4NmM1YjFmODJlMTFlZDg5ZDBiODhmYmI3YWQ0MDlfNjEubXA0/0.flac
06/06 02:34:41 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:14/28:49] Structures From Silence - Steve Roach
06/06 02:34:43 Info: [stats] 30101mb Virtual, 2132mb Physical, 595mb Managed, 329 Handles, 97 Threads
06/06 02:34:46 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:19/28:49] Structures From Silence - Steve Roach
06/06 02:34:51 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:24/28:49] Structures From Silence - Steve Roach
06/06 02:34:56 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=30524908733 rtt=1500us offset=-7982091us delta=461us drift=-431us in 30.446s (-14.171ppm, -51.014ms/hr)
06/06 02:34:56 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:29/28:49] Structures From Silence - Steve Roach
06/06 02:34:58 Info: [stats] 30045mb Virtual, 2136mb Physical, 487mb Managed, 329 Handles, 94 Threads
06/06 02:35:01 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:35/28:49] Structures From Silence - Steve Roach
06/06 02:35:06 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:40/28:49] Structures From Silence - Steve Roach
06/06 02:35:11 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:45/28:49] Structures From Silence - Steve Roach
06/06 02:35:13 Info: [stats] 30029mb Virtual, 2137mb Physical, 515mb Managed, 329 Handles, 88 Threads
06/06 02:35:16 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:50/28:49] Structures From Silence - Steve Roach
06/06 02:35:21 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 0:55/28:49] Structures From Silence - Steve Roach
06/06 02:35:26 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:00/28:49] Structures From Silence - Steve Roach
06/06 02:35:26 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=61006267925 rtt=1500us offset=-7982732us delta=-1351us drift=-1072us in 60.927s (-17.599ppm, -63.356ms/hr)
06/06 02:35:28 Info: [stats] 30037mb Virtual, 2137mb Physical, 538mb Managed, 329 Handles, 93 Threads
06/06 02:35:31 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:05/28:49] Structures From Silence - Steve Roach
06/06 02:35:37 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:10/28:49] Structures From Silence - Steve Roach
06/06 02:35:42 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:15/28:49] Structures From Silence - Steve Roach
06/06 02:35:43 Info: [stats] 30029mb Virtual, 2139mb Physical, 559mb Managed, 329 Handles, 88 Threads
06/06 02:35:47 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:21/28:49] Structures From Silence - Steve Roach
06/06 02:35:49 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=83476567248 rtt=89500us offset=-8070432us delta=-87781us drift=-88772us in 83.398s (-1064.449ppm, -3832.017ms/hr)
06/06 02:35:52 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:26/28:49] Structures From Silence - Steve Roach
06/06 02:35:57 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=91525283154 rtt=1500us offset=-7982716us delta=-300us drift=-1057us in 91.447s (-11.559ppm, -41.612ms/hr)
06/06 02:35:57 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:31/28:49] Structures From Silence - Steve Roach
06/06 02:35:58 Info: [stats] 30045mb Virtual, 2141mb Physical, 592mb Managed, 327 Handles, 94 Threads
06/06 02:36:02 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:36/28:49] Structures From Silence - Steve Roach
06/06 02:36:07 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:41/28:49] Structures From Silence - Steve Roach
06/06 02:36:12 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:46/28:49] Structures From Silence - Steve Roach
06/06 02:36:13 Info: [stats] 30005mb Virtual, 2145mb Physical, 616mb Managed, 327 Handles, 84 Threads
06/06 02:36:17 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:51/28:49] Structures From Silence - Steve Roach
06/06 02:36:22 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 1:56/28:49] Structures From Silence - Steve Roach
06/06 02:36:24 Info: [brokerserver] Client disconnected: 192.168.50.111:50278
06/06 02:36:24 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/06 02:36:24 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/06 02:36:24 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
06/06 02:36:24 Trace: [push] retrying connection in 95115ms
06/06 02:36:24 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/06 02:36:24 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 500ms
06/06 02:36:25 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 2)
06/06 02:36:25 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/06 02:36:25 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 750ms
06/06 02:36:26 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 3)
06/06 02:36:26 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/06 02:36:26 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 1125ms
06/06 02:36:27 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 4)
06/06 02:36:27 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/06 02:36:27 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 1687ms
06/06 02:36:27 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:01/28:49] Structures From Silence - Steve Roach
06/06 02:36:27 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=122017246317 rtt=1500us offset=-7982753us delta=53us drift=-1093us in 121.9385s (-8.971ppm, -32.294ms/hr)
06/06 02:36:28 Info: [stats] 29997mb Virtual, 2149mb Physical, 645mb Managed, 324 Handles, 85 Threads
06/06 02:36:28 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 5)
06/06 02:36:28 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/06 02:36:28 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Giving up
06/06 02:36:28 Trace: [raat] [sood] Refreshing device list
06/06 02:36:30 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=124331385705 rtt=91500us offset=-7913614us delta=69139us drift=68045us in 124.077s (548.414ppm, 1974.290ms/hr)
06/06 02:36:32 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:06/28:49] Structures From Silence - Steve Roach
06/06 02:36:35 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
06/06 02:36:35 Trace: [push] connecting to 35.237.106.222:9204
06/06 02:36:35 Trace: [push] connected
06/06 02:36:37 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:11/28:49] Structures From Silence - Steve Roach
06/06 02:36:42 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:16/28:49] Structures From Silence - Steve Roach
06/06 02:36:43 Info: [stats] 30053mb Virtual, 2159mb Physical, 670mb Managed, 326 Handles, 94 Threads
06/06 02:36:46 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=140385601961 rtt=86500us offset=-8066398us delta=-85390us drift=-84738us in 140.306s (-603.953ppm, -2174.230ms/hr)
06/06 02:36:47 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:21/28:49] Structures From Silence - Steve Roach
06/06 02:36:48 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=142489573661 rtt=92500us offset=-8072426us delta=-6028us drift=-90766us in 142.41s (-637.361ppm, -2294.498ms/hr)
06/06 02:36:50 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=144746124110 rtt=200000us offset=-8181875us delta=-109449us drift=-200216us in 144.6685s (-1383.964ppm, -4982.272ms/hr)
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":664,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=146761186851 rtt=87500us offset=-7947813us delta=234062us drift=33846us in 146.562s (230.938ppm, 831.375ms/hr)
06/06 02:36:52 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:25/28:49] Structures From Silence - Steve Roach
06/06 02:36:53 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:53 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/06 02:36:53 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/06 02:36:53 Warn: [zone Bedroom] Track Stopped Due to Slow Media
06/06 02:36:53 Info: [audio/env] [zoneplayer] All streams were disposed
06/06 02:36:53 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/06 02:36:53 Trace: [Bedroom] [zoneplayer/raat] Endpoint E30 State Changed: Playing => Prepared
06/06 02:36:53 Trace: [E30] [raatclient] SENT [9]{"request":"end_stream"}
06/06 02:36:53 Debug: [raat/tcpaudiosource] disconnecting
06/06 02:36:53 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/06 02:36:53 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object.
06/06 02:36:53 Warn: [raat/tcpaudiosource] disconnecting + retrying
06/06 02:36:53 Info: [library] recorded play for profile 3b291f34-c94c-4c52-970a-63ed8a3d30cf: mediaid=168:0:41132325 metadataid= contentid=168:0:41132325 libraryid= isfromswim=True

Arthur_Gonzales · June 7, 2021, 12:55pm

Hi @dylan , had two skips in 5 hours of playback today. One track played only up to 75% then the next track was just skipped completely. Both tracks were from Qobuz this time around. Here’s the part of the log file that could help us track down the problem:

06/07 11:15:45 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [83% buf] [PLAYING @ 13:50/14:03] Piano Concerto No. 20 in D minor, K. 466: Allegro - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:15:46 Info: [stats] 30291mb Virtual, 2043mb Physical, 490mb Managed, 322 Handles, 111 Threads
06/07 11:15:50 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [31% buf] [PLAYING @ 13:55/14:03] Piano Concerto No. 20 in D minor, K. 466: Allegro - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:15:53 Trace: [prebuffer] ready 149940/441000 (34%) @ 0/535 sec
06/07 11:15:54 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-1.7399999999999998 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=32 Quality=Lossless
    Raat Device=E30
    Output OutputType=Local_Alsa Quality=Lossless SubType= Model=E30
------------------------------------------------------------
06/07 11:15:55 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [3% buf] [PLAYING @ 14:00/14:03] Piano Concerto No. 20 in D minor, K. 466: Allegro - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:15:58 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Enhanced
Elements:
    Source Format=Flac 44100/16/2  Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=16 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-1.7399999999999998 Quality=Enhanced
    Truncate FromBitsPerSample=64 ToBitsPerSample=32 Quality=Lossless
    Raat Device=E30
    Output OutputType=Local_Alsa Quality=Lossless SubType= Model=E30
------------------------------------------------------------
06/07 11:15:58 Info: [zone Bedroom] OnPlayFeedback OnToNext
06/07 11:15:58 Debug: [zone Bedroom] _Advance
06/07 11:15:58 Info: [library] recorded play for profile 3b291f34-c94c-4c52-970a-63ed8a3d30cf: mediaid=202:0:24076681 metadataid= contentid=202:0:24076681 libraryid= isfromswim=False
06/07 11:15:58 Debug: [query] Sooloos.Broker.Transport.TransportItem: 6315 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
06/07 11:15:58 Debug: [query] Sooloos.Broker.Transport.TransportItem: 6315 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
06/07 11:15:58 Info: [zone Bedroom] OnPlayFeedback Playing
06/07 11:15:59 Debug: [easyhttp] [18687] GET to https://metadata.roonlabs.net/1/tracks/202:0:24076683/lyrics returned after 360 ms, status code: 404
06/07 11:15:59 Debug: [easyhttp] [18685] GET to https://www.qobuz.com/api.json/0.2/track/getFileUrl?format_id=27&intent=stream&request_sig=7296e74c770fd9609f2829f6b09fb472&request_ts=1623064558&track_id=53606965 returned after 1011 ms, status code: 200
06/07 11:15:59 Info: [Bedroom] [zoneplayer] Queueing: https://streaming-std.qobuz.com/file
06/07 11:16:00 Debug: [easyhttp] [18686] POST to https://www.qobuz.com/api.json/0.2/track/reportStreamingStart? returned after 1067 ms, status code: 201
06/07 11:16:00 Warn: [qobuz] [http] error result from http request: {"transUId":"076763759869001710498472001000792","status":"success","code":200}
06/07 11:16:00 Debug: [easyhttp] [18684] POST to https://www.qobuz.com/api.json/0.2/track/reportStreamingEnd? returned after 1254 ms, status code: 200
06/07 11:16:00 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:02/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:01 Info: [Bedroom] [zoneplayer] Open result (Queueing): Result[Status=Success]
06/07 11:16:01 Info: [stats] 30187mb Virtual, 2047mb Physical, 518mb Managed, 323 Handles, 95 Threads
06/07 11:16:05 Trace: [streamingmediafile] finished caching https://streaming-std.qobuz.com/file
06/07 11:16:05 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:07/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:07 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=12020415856092 rtt=5000us offset=-46247301143us delta=-1296us drift=-49699us in 6011.3745s (-8.268ppm, -29.763ms/hr)
06/07 11:16:10 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:12/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:12 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 644525303 bytes, 577559 ops in 120392 ms)
06/07 11:16:12 Debug: [music/profilestats] Computed stats in 60ms, got 50 top albums, 50 top performers, 27 genres
06/07 11:16:12 Trace: [dbperf] flush 0 bytes, 0 ops in 4 ms (cumulative 644525303 bytes, 577559 ops in 120396 ms)
06/07 11:16:12 Trace: [radio/query] performing channel query
06/07 11:16:12 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
06/07 11:16:12 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 644525303 bytes, 577559 ops in 120397 ms)
06/07 11:16:12 Debug: [music/profilestats] Computed stats in 39ms, got 50 top albums, 50 top performers, 27 genres
06/07 11:16:12 Trace: [radio/query] performing channel query
06/07 11:16:12 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
06/07 11:16:12 Debug: [music/profilestats] Computed stats in 46ms, got 50 top albums, 50 top performers, 27 genres
06/07 11:16:12 Trace: [radio/query] performing channel query
06/07 11:16:12 Trace: [radio/query] query returned 0 Sooloos.Broker.Api.Channel(s)
06/07 11:16:12 Debug: GET /image/nxiaaaaa.1024.jpg
06/07 11:16:12 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=SG&offset=0&limit=30 => Success
06/07 11:16:12 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=SG&offset=0&limit=30 => Success
06/07 11:16:12 Trace: [tidal/http] GET https://api.tidal.com/v1/featured/recommended/playlists?countryCode=SG&offset=0&limit=30 => Success
06/07 11:16:12 Debug: [easyhttp] [18691] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/performers/recommended?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 385 ms, status code: 404
06/07 11:16:12 Debug: [easyhttp] [18690] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/genres?c=tidal-sg,qobuz-us returned after 419 ms, status code: 200
06/07 11:16:12 Debug: [easyhttp] [18698] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/performers/recommended?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 377 ms, status code: 404
06/07 11:16:12 Debug: [easyhttp] [18697] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/genres?c=tidal-sg,qobuz-us returned after 415 ms, status code: 200
06/07 11:16:12 Debug: [easyhttp] [18693] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/collaboration?c=tidal-sg,qobuz-us returned after 476 ms, status code: 200
06/07 11:16:12 Debug: [easyhttp] [18705] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/performers/recommended?profile=3b291f34-c94c-4c52-970a-63ed8a3d30cf&c=tidal-sg,qobuz-us returned after 364 ms, status code: 404
06/07 11:16:13 Trace: [dbperf] flush 0 bytes, 0 ops in 3 ms (cumulative 644525303 bytes, 577559 ops in 120400 ms)
06/07 11:16:13 Debug: [easyhttp] [18700] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/collaboration?c=tidal-sg,qobuz-us returned after 484 ms, status code: 200
06/07 11:16:13 Debug: [easyhttp] [18704] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/genres?c=tidal-sg,qobuz-us returned after 432 ms, status code: 200
06/07 11:16:13 Debug: [easyhttp] [18707] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/collaboration?c=tidal-sg,qobuz-us returned after 434 ms, status code: 200
06/07 11:16:13 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 644525303 bytes, 577559 ops in 120401 ms)
06/07 11:16:13 Debug: [easyhttp] [18688] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/albums/newv3?c=tidal-sg,qobuz-us returned after 680 ms, status code: 200
06/07 11:16:13 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 644525303 bytes, 577559 ops in 120402 ms)
06/07 11:16:13 Debug: [easyhttp] [18695] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/albums/newv3?c=tidal-sg,qobuz-us returned after 597 ms, status code: 200
06/07 11:16:13 Trace: [dbperf] flush 0 bytes, 0 ops in 1 ms (cumulative 644525303 bytes, 577559 ops in 120403 ms)
06/07 11:16:13 Debug: [easyhttp] [18702] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/albums/newv3?c=tidal-sg,qobuz-us returned after 609 ms, status code: 200
06/07 11:16:13 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 644525303 bytes, 577559 ops in 120405 ms)
06/07 11:16:13 Debug: [easyhttp] [18696] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 1039 ms, status code: 304
06/07 11:16:13 Debug: [easyhttp] [18689] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 1105 ms, status code: 304
06/07 11:16:13 Debug: [easyhttp] [18703] GET to https://internetradio.roonlabs.net/2/api/stations/all?count=100&sortby=popular&location=auto&maxDistance=100&format=msgpack returned after 1024 ms, status code: 304
06/07 11:16:13 Debug: GET /image/ndaaaaaa.1024.jpg
06/07 11:16:13 Debug: [easyhttp] [18701] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=2ffbf18b9148476e6aaa0307fb53abe6&request_ts=1623064572&&type=editor-picks returned after 1220 ms, status code: 200
06/07 11:16:13 Debug: [easyhttp] [18694] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=2ffbf18b9148476e6aaa0307fb53abe6&request_ts=1623064572&&type=editor-picks returned after 1296 ms, status code: 200
06/07 11:16:13 Debug: [easyhttp] [18708] GET to https://www.qobuz.com/api.json/0.2/playlist/getFeatured?&limit=30&offset=0&request_sig=2ffbf18b9148476e6aaa0307fb53abe6&request_ts=1623064572&&type=editor-picks returned after 1275 ms, status code: 200
06/07 11:16:15 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:17/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:16 Info: [stats] 30171mb Virtual, 2093mb Physical, 555mb Managed, 325 Handles, 93 Threads
06/07 11:16:20 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:22/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:25 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:27/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:30 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:16:30 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:16:30 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:16:30 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:16:30 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:16:30 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:31/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:31 Info: [stats] 30163mb Virtual, 2097mb Physical, 570mb Managed, 325 Handles, 92 Threads
06/07 11:16:35 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:37/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:38 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12051028303507 rtt=50000us offset=-46247255696us delta=45194us drift=-4252us in 6041.8995s (-0.704ppm, -2.534ms/hr)
06/07 11:16:40 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=12052868510728 rtt=1500us offset=-46247301489us delta=-45792us drift=-50044us in 6043.8345s (-8.280ppm, -29.809ms/hr)
06/07 11:16:40 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:16:40 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:16:40 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:16:40 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:16:40 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:16:40 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:42/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:43 Debug: [easyhttp] [18692] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/coveredperformer?c=tidal-sg,qobuz-us returned after 31366 ms, status code: 404
06/07 11:16:45 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:47/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:46 Info: [stats] 30163mb Virtual, 2098mb Physical, 578mb Managed, 324 Handles, 92 Threads
06/07 11:16:50 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:16:50 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:16:50 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:16:50 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:16:50 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:16:50 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:51/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:55 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 0:57/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:16:56 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12069695153650 rtt=180500us offset=-46247134846us delta=174531us drift=116598us in 6060.316s (19.240ppm, 69.263ms/hr)
06/07 11:16:58 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12071503732172 rtt=183000us offset=-46247162267us delta=-27421us drift=89176us in 6062.1495s (14.710ppm, 52.957ms/hr)
06/07 11:17:00 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:17:00 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:17:00 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:17:00 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:17:00 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:17:00 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:02/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:01 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12073855138923 rtt=282000us offset=-46247038861us delta=123406us drift=212583us in 6064.2785s (35.055ppm, 126.198ms/hr)
06/07 11:17:01 Info: [stats] 30171mb Virtual, 2109mb Physical, 596mb Managed, 324 Handles, 97 Threads
06/07 11:17:02 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12075587174895 rtt=231000us offset=-46247083825us delta=-44964us drift=167619us in 6066.1065s (27.632ppm, 99.476ms/hr)
06/07 11:17:05 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:07/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:10 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=12083320633871 rtt=4000us offset=-46247300366us delta=1002us drift=-48921us in 6074.274s (-8.054ppm, -28.994ms/hr)
06/07 11:17:10 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:17:10 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:17:10 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:17:10 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:17:10 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:17:11 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:12/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:12 Debug: [easyhttp] [18699] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/coveredperformer?c=tidal-sg,qobuz-us timed out after 60002 ms
06/07 11:17:12 Debug: [easyhttp] [18706] GET to https://discover.roonlabs.net/1/profiles/3b291f34-c94c-4c52-970a-63ed8a3d30cf/featured/coveredperformer?c=tidal-sg,qobuz-us timed out after 60001 ms
06/07 11:17:16 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:17/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:16 Info: [stats] 30155mb Virtual, 2114mb Physical, 601mb Managed, 322 Handles, 91 Threads
06/07 11:17:20 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:17:20 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:17:20 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:17:20 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connected
06/07 11:17:20 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
06/07 11:17:21 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:22/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:21 Info: [brokerserver] Client disconnected: 192.168.50.111:52080
06/07 11:17:21 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
06/07 11:17:21 Trace: [push] retrying connection in 15972ms
06/07 11:17:26 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:27/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:30 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
06/07 11:17:30 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] lost client connection. Retrying
06/07 11:17:30 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 1)
06/07 11:17:31 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:32/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:31 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/07 11:17:31 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 500ms
06/07 11:17:31 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
06/07 11:17:31 Trace: [push] connecting to 35.243.240.56:9204
06/07 11:17:31 Trace: [push] connected
06/07 11:17:31 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 2)
06/07 11:17:31 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/07 11:17:31 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 750ms
06/07 11:17:31 Info: [stats] 30139mb Virtual, 2129mb Physical, 615mb Managed, 319 Handles, 90 Threads
06/07 11:17:32 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 3)
06/07 11:17:32 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/07 11:17:32 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 1125ms
06/07 11:17:33 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 4)
06/07 11:17:33 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/07 11:17:33 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Retrying in 1687ms
06/07 11:17:35 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] connecting (attempt 5)
06/07 11:17:36 Warn: [rnet/RnetJsonClient] failed to connect Connection refused [::ffff:192.168.50.111]:9200
06/07 11:17:36 Trace: [raatserver] [RaatServer Arthurs-iPhone @ 192.168.50.111:9200] client connection failed. Giving up
06/07 11:17:36 Trace: [raat] [sood] Refreshing device list
06/07 11:17:36 Trace: [push] restarting connection (Unable to read data from the transport connection: Software caused connection abort.)
06/07 11:17:36 Trace: [push] retrying connection in 24716ms
06/07 11:17:36 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:37/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:36 Debug: [easyhttp] [18710] POST to https://swim.roonlabs.net/1/session/e254c313e0b74ebd8f65ddd1e1a2e873/ping returned after 388 ms, status code: 200
06/07 11:17:41 Trace: [Bedroom] [zoneplayer/raat] sync E30: realtime=12113817014620 rtt=4500us offset=-46247299985us delta=1842us drift=-48541us in 6104.766s (-7.951ppm, -28.625ms/hr)
06/07 11:17:41 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:42/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:46 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:47/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:46 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
06/07 11:17:46 Trace: [push] connecting to 35.196.114.77:9204
06/07 11:17:46 Trace: [push] connected
06/07 11:17:47 Info: [stats] 30219mb Virtual, 2134mb Physical, 620mb Managed, 320 Handles, 102 Threads
06/07 11:17:51 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:52/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:17:56 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 1:57/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:18:00 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=12132452955239 rtt=302000us offset=-46247530044us delta=-227951us drift=-278600us in 6123.3475s (-45.498ppm, -163.793ms/hr)
06/07 11:18:01 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:02/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:18:02 Info: [stats] 30211mb Virtual, 2142mb Physical, 511mb Managed, 319 Handles, 97 Threads
06/07 11:18:07 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [PLAYING @ 2:08/8:55] Piano Concerto No. 20 in D minor, K. 466: Romance - Orchestra della Svizzera Italiana / Jacek Kaspszyk / Wolfgang Amadeus Mozart
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":127,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/07 11:18:09 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/07 11:18:09 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/07 11:18:09 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/07 11:18:09 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/07 11:18:09 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/07 11:18:09 Warn: [zone Bedroom] Track Stopped Due to Slow Media
06/07 11:18:09 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/07 11:18:09 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/07 11:18:09 Trace: [Bedroom] [zoneplayer/raat] Endpoint E30 State Changed: Playing => Prepared
06/07 11:18:09 Trace: [E30] [raatclient] SENT [44]{"request":"end_stream"}
06/07 11:18:09 Info: [audio/env] [zoneplayer] All streams were disposed
06/07 11:18:09 Debug: [raat/tcpaudiosource] disconnecting
06/07 11:18:09 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object.
06/07 11:18:09 Warn: [raat/tcpaudiosource] disconnecting + retrying
06/07 11:18:09 Info: [library] recorded play for profile 3b291f34-c94c-4c52-970a-63ed8a3d30cf: mediaid=202:0:24076683 metadataid= contentid=202:0:24076683 libraryid= isfromswim=False
06/07 11:18:09 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = Inactive
Elements:
------------------------------------------------------------
06/07 11:18:09 Info: [zone Bedroom] OnPlayFeedback StoppedEndOfMediaUnnatural
06/07 11:18:09 Debug: [zone Bedroom] _Advance
06/07 11:18:09 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:09 Info: sleep 40ms after flush
06/07 11:18:09 Info: [Bedroom] [zoneplayer] BufferingTrack == NextTrack during ClearQueuedMedia, setting _stop_on_next_track_transition
06/07 11:18:09 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/07 11:18:09 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/07 11:18:09 Trace: [Bedroom] [Enhanced, 16/44 QOBUZ FLAC => 32/44] [100% buf] [LOADING @ 0:00] Piano Concerto No. 25 in C Major, K. 503: III. Allegretto (Live) - Martha Argerich / Jacek Kaspszyk / Orchestra della Svizzera Italiana / Wolfgang Amadeus Mozart
06/07 11:18:09 Debug: [query] Sooloos.Broker.Transport.TransportItem: 6315 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
06/07 11:18:09 Debug: [query] Sooloos.Broker.Transport.TransportItem: 6315 dirty items. rebuilding query instead of re-sorting item-by-item (internaltype=TransportItem)
06/07 11:18:10 Debug: [easyhttp] [18717] GET to https://metadata.roonlabs.net/1/albums/200:0:nedkn7ugoj3ua/credits?c=qobuz-us returned after 163 ms, status code: 304
06/07 11:18:10 Debug: [easyhttp] [18716] GET to https://metadata.roonlabs.net/1/albums/200:0:nedkn7ugoj3ua/tracks?c=qobuz-us returned after 166 ms, status code: 304
06/07 11:18:10 Trace: [E30] [raatclient] GOT [42] {"samples":22050,"status":"Dropout"}
06/07 11:18:10 Trace: [E30] [raatclient] GOT [42] {"status":"Ended"}
06/07 11:18:10 Trace: [E30] [raatclient] GOT [44] {"status":"Success"}
06/07 11:18:10 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/07 11:18:10 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream

dylan · June 7, 2021, 5:46pm

Thanks for your patience over the weekend, @Arthur_Gonzales

I took a look at the diagnostics report and it looks like it is not the issue I was originally thinking it was.

For the Structures From Silence example, this is the key trace here:

06/06 02:36:47 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:21/28:49] Structures From Silence - Steve Roach
06/06 02:36:48 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=142489573661 rtt=92500us offset=-8072426us delta=-6028us drift=-90766us in 142.41s (-637.361ppm, -2294.498ms/hr)
06/06 02:36:50 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=144746124110 rtt=200000us offset=-8181875us delta=-109449us drift=-200216us in 144.6685s (-1383.964ppm, -4982.272ms/hr)
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":664,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:52 Warn: [Bedroom] [zoneplayer/raat] long rtt sync E30: realtime=146761186851 rtt=87500us offset=-7947813us delta=234062us drift=33846us in 146.562s (230.938ppm, 831.375ms/hr)
06/06 02:36:52 Trace: [Bedroom] [Enhanced, 16/44 TIDAL FLAC => 32/44] [100% buf] [PLAYING @ 2:25/28:49] Structures From Silence - Steve Roach
06/06 02:36:53 Trace: [E30] [raatclient] GOT [7] {"samples":22050,"status":"Dropout"}
06/06 02:36:53 Warn: [Bedroom] [zoneplayer/raat] Too many dropouts (>3s dropped out in the last 30s). Killing stream
06/06 02:36:53 Trace: [Bedroom] [zoneplayer/raat] too many dropouts. stopping stream
06/06 02:36:53 Warn: [zone Bedroom] Track Stopped Due to Slow Media
06/06 02:36:53 Info: [audio/env] [zoneplayer] All streams were disposed
06/06 02:36:53 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/06 02:36:53 Trace: [Bedroom] [zoneplayer/raat] Endpoint E30 State Changed: Playing => Prepared
06/06 02:36:53 Trace: [E30] [raatclient] SENT [9]{"request":"end_stream"}
06/06 02:36:53 Debug: [raat/tcpaudiosource] disconnecting
06/06 02:36:53 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/06 02:36:53 Warn: [raat/tcpaudiosource] send failed: Object reference not set to an instance of an object.
06/06 02:36:53 Warn: [raat/tcpaudiosource] disconnecting + retrying
06/06 02:36:53 Info: [library] recorded play for profile 3b291f34-c94c-4c52-970a-63ed8a3d30cf: mediaid=168:0:41132325 metadataid= contentid=168:0:41132325 libraryid= isfromswim=True
06/06 02:36:53 Info:

It shows that your device started getting dropouts, and then stopped.

This Bedroom zone — Can you elaborate a bit more about what this device is and how it’s connected?

Do you have a DAC you could connect via USB to the Core? It would be interesting to know if the same behavior occurs without involving the network when playing back content.

Arthur_Gonzales · June 8, 2021, 4:08am

Hi Dylan, thanks for getting back to me.

The Bedroom zone is a Raspberry Pi 3 B+ with 32 GB SSD and 2GB RAM running on RopieeeXL, connected via WiFi. It outputs to a Topping E30 DAC via USB.

Yes, I will try that later today.

Are you saying it’s a network issue? I’ve upgraded my mesh network to the ASUS XT8. I’ve been getting the best connections I’ve ever had in all my devices and applications bar the Roon ROCK, Roon Bridge, and Roon Remote. So if it’s a network issue, it might just be your platform’s network issue.

dylan · June 16, 2021, 8:39pm

Hi @Arthur_Gonzales

It’s hard to say if it is for sure networking — Basically the dropouts tell us that your device is not getting data fast enough. This could be a Core performance problem, a local network problem, or perhaps even something with this endpoint. Testing locally will help rule out some variables.

Were you able to give that a try?

Arthur_Gonzales · June 17, 2021, 12:58pm

Without changing anything, I’ve been getting consistent playback and no skips in all my endpoints. Could it be related to the Qobuz issue?

Yes, will give this a try this weekend.

Thanks, Dylan!

Arthur_Gonzales · June 21, 2021, 2:22pm

Hi Dylan,

I have had a few days with no skips and stops. I think my issue has now been resolved. My hunch is it’s related to the Qobuz issue.

Thanks for all your help!

And thank you also, @beka !