Issues with Music Playback and "No Audio Devices Found" Message (ref#O10N4F)

pfausto · April 23, 2024, 2:06am

Full form submission

What’s happening?

· I'm having trouble playing music

What best describes your playback issue?

· Music stops playing mid-track

Describe the issue

After the latest Roon upgrade to build 1401, I have been experiencing frequent issues with music playback stopping partway through a song. When it happens the play bar briefly disappears and I see a message saying "No audio devices found" with a button to "Manage audio devices". After a second or two, the play bar reappears in its default state. And after 30 seconds to a minute, the play bar re-populates with the song I had been playing paused in the position where it had dropped out.

Additionally, if I open the Settings > About page and sit there with it open, I will intermittently see a "Waiting for your Roon Server" spinner appear.

I listen from Roon ARC sometimes at work as well, and today I received a "Poor connection. Try again or listen to your downloads" snackbar with a "Retry" button a couple times.

I have Roon Server running on Ubuntu 22.04.4 with plenty of available CPU and memory (though Roon is using a surprising amount of the latter). The client is running on Windows 11, and the two machines are connected via 2.5GbE interfaces by way of a 2.5GbE switch. Roon ARC is installed on my Pixel 6 Pro, and I usually VPN back to home while listening for a more consistent experience.

I skimmed through a few dozen of the most recent posts on the support forum and found several that appear to match the issue I'm seeing (though I understand similar symptoms can have different causes):

pfausto · April 23, 2024, 4:12am

Part 2

04/22 21:33:07 Info: [stats] 29655mb Virtual, 16597mb Physical, 9483mb Managed, 1880 Handles, 157 Threads
04/22 21:33:10 Trace: [library] endmutation in 171ms
04/22 21:33:11 Trace: [library] endmutation in 169ms
04/22 21:33:12 Trace: [library] endmutation in 167ms
04/22 21:33:12 Trace: [library] endmutation in 170ms
04/22 21:33:13 Trace: [library] endmutation in 166ms
04/22 21:33:13 Trace: [library] endmutation in 170ms
04/22 21:33:15 Trace: [library] endmutation in 172ms
04/22 21:33:17 Info: [library] recorded play for profile 2403d835-5999-41a2-b3bf-d51424b246da: mediaid=50:1:16c60512-e24e-4142-9ffb-f2a727984c7b metadataid=97:0:279593032259484 contentid= libraryid=50:1:16c60512-e24e-4142-9ffb-f2a727984c7b isfromradio=False isfrommix=False isfrommobile=
04/22 21:33:17 Trace: [library] finished with 43 dirty tracks 3 dirty albums 1 dirty performers 43 dirty works 43 dirty performances 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 0 tracks to (re)load, 0 tracks to retain, 0 auxfiles to (re)load, 0 auxfiles to retain, and 91 changed objects
04/22 21:33:17 Info: [library/albumdetails] found 1 ingroup alternates, 0 other alternates
04/22 21:33:17 Trace: [music/searchindex] [search-index]  removed in 0ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
04/22 21:33:17 Trace: computing profile stats
04/22 21:33:17 Trace: done computing profile stats
04/22 21:33:17 Trace: [music/searchindex] [search-index] added in 0ms: 1 albums, 0 tracks, 0 works, 0 performers, 0 labels, 0 genres
04/22 21:33:17 Trace: [music/searchindex] totals: 1466 albums, 16515 tracks, 10037 works, 20402 performers, 625 labels, 1261 genres
04/22 21:33:17 Debug: [music/profilestats] Computed stats in 9ms, got 50 top albums, 50 top performers, 31 genres
04/22 21:33:17 Trace: [library] endmutation in 47ms
04/22 21:33:20 Trace: [library] endmutation in 172ms
04/22 21:33:22 Info: [stats] 29663mb Virtual, 16588mb Physical, 8799mb Managed, 1880 Handles, 158 Threads
04/22 21:33:22 Trace: [library] endmutation in 166ms
04/22 21:33:23 Trace: [library] endmutation in 167ms
04/22 21:33:24 Trace: [library] endmutation in 166ms
04/22 21:33:25 Trace: [library] endmutation in 165ms
04/22 21:33:26 Trace: [library] endmutation in 169ms
04/22 21:33:28 Trace: [library] endmutation in 163ms
04/22 21:33:28 Trace: [library] endmutation in 163ms
04/22 21:33:30 Trace: [library] endmutation in 164ms
04/22 21:33:31 Trace: [library] endmutation in 164ms
04/22 21:33:34 Trace: [library] endmutation in 156ms
04/22 21:33:34 Trace: [library] endmutation in 150ms
04/22 21:33:36 Trace: [library] endmutation in 167ms
04/22 21:33:37 Info: [stats] 29567mb Virtual, 16598mb Physical, 10009mb Managed, 1880 Handles, 146 Threads
04/22 21:33:38 Trace: [library] endmutation in 163ms
04/22 21:33:38 Trace: [library] endmutation in 100ms
04/22 21:33:41 Trace: [library] endmutation in 158ms
04/22 21:33:41 Trace: [library] endmutation in 154ms
04/22 21:33:42 Trace: [library] endmutation in 155ms
04/22 21:33:43 Trace: [library] endmutation in 154ms
04/22 21:33:44 Trace: [library] endmutation in 154ms
04/22 21:33:44 Trace: [library] endmutation in 153ms
04/22 21:33:46 Trace: [library] endmutation in 153ms
04/22 21:33:46 Trace: [library] endmutation in 157ms
04/22 21:33:47 Trace: [library] endmutation in 154ms
04/22 21:33:50 Trace: [library] endmutation in 147ms
04/22 21:33:51 Trace: [zone Someone-Windows10] Loaded Queue=2 Tracks Swim=Inactive AutoSwim=False Loop=Disabled Shuffle=False
04/22 21:33:51 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PAUSED @ 1:28/2:46] Lace - Christopher Larkin
04/22 21:33:51 Trace: [zone Firebolt] Loaded Queue=56 Tracks Swim=Inactive AutoSwim=False Loop=Disabled Shuffle=False
04/22 21:33:51 Info: [broker/locations] updating location Qobuz:Name=QOBUZ:Id=f4845d8f-2574-4afe-9fbf-0a3ba8f37867
04/22 21:33:51 Trace: [zone Kitchen speaker] Loaded Queue=0 Tracks Swim=Inactive AutoSwim=True Loop=Disabled Shuffle=False
04/22 21:33:51 Debug: [easyhttp] [17700] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:536888&type=biography&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=qobuz-us&contentPreferences=reallyAvoidMqa&tidal=max returned after 72 ms, status code: 200, request body size: 0 B
04/22 21:33:52 Info: [stats] 29583mb Virtual, 16593mb Physical, 9027mb Managed, 1879 Handles, 148 Threads
04/22 21:34:01 Debug: [easyhttp] [17701] POST to https://api.roonlabs.net/device-map/1/register returned after 188 ms, status code: 200, request body size: 7 KB
04/22 21:34:01 Trace: [devicemap] device map updated
04/22 21:34:07 Info: [stats] 29607mb Virtual, 16595mb Physical, 9039mb Managed, 1879 Handles, 151 Threads

Client (not much of interest):

04/22 21:30:31 Debug: GMS: saving nav stack
04/22 21:30:31 Debug: GMS: trying to save nav stack, but nav stack stuff was in progress
04/22 21:30:31 Trace: waveformshader(1491) texture loaded: 121
04/22 21:30:31 Debug: GMS: done saving nav stack
04/22 21:30:31 Debug: GMS: saving nav stack
04/22 21:30:31 Debug: GMS: done saving nav stack
04/22 21:30:40 Info: [stats] 2103966mb Virtual, 645mb Physical, 230mb Managed, 1368 Handles, 56 Threads
04/22 21:30:55 Info: [stats] 2103945mb Virtual, 646mb Physical, 231mb Managed, 1371 Handles, 42 Threads
04/22 21:31:10 Info: [stats] 2103941mb Virtual, 639mb Physical, 231mb Managed, 1354 Handles, 39 Threads
04/22 21:31:25 Info: [stats] 2103941mb Virtual, 625mb Physical, 230mb Managed, 1359 Handles, 39 Threads
04/22 21:31:40 Info: [stats] 2103947mb Virtual, 627mb Physical, 230mb Managed, 1385 Handles, 43 Threads
04/22 21:31:55 Info: [stats] 2103945mb Virtual, 636mb Physical, 229mb Managed, 1387 Handles, 42 Threads
04/22 21:31:59 Debug: GMS: saving nav stack
04/22 21:32:04 Debug: GMS: done saving nav stack
04/22 21:32:10 Info: [stats] 2103944mb Virtual, 650mb Physical, 212mb Managed, 1392 Handles, 41 Threads
04/22 21:32:25 Info: [stats] 2103944mb Virtual, 650mb Physical, 252mb Managed, 1395 Handles, 41 Threads
04/22 21:32:40 Info: [stats] 2103942mb Virtual, 629mb Physical, 195mb Managed, 1137 Handles, 40 Threads
04/22 21:32:55 Info: [stats] 2103941mb Virtual, 629mb Physical, 208mb Managed, 1137 Handles, 39 Threads
04/22 21:33:10 Info: [stats] 2103941mb Virtual, 636mb Physical, 223mb Managed, 1146 Handles, 39 Threads
04/22 21:33:25 Info: [stats] 2103941mb Virtual, 643mb Physical, 197mb Managed, 1126 Handles, 39 Threads
04/22 21:33:40 Info: [stats] 2103941mb Virtual, 643mb Physical, 211mb Managed, 1133 Handles, 39 Threads
04/22 21:33:51 Debug: GMS: saving nav stack
04/22 21:33:51 Trace: waveformshader(1491) texture loaded: 124
04/22 21:33:51 Debug: GMS: done saving nav stack
04/22 21:33:51 Debug: GMS: saving nav stack
04/22 21:33:51 Debug: GMS: done saving nav stack

RAATServer (on the client side):

04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  GOT [12] {"request":"stream","stream_id":1249072487,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  SENT [12] {"status":"Buffering"}
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  SENT [12] {"status":"Ready"}
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  GOT [13] {"request":"start","min_offset":520000000,"stream_sample":0}
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  Doing ASAP start based on current device clock (min_offset=520000000)
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  SENT [12] {"status":"Playing"}
04/22 21:30:31 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  SENT [13] {"time":575231000,"status":"Success"}
04/22 21:30:31 Trace: [RAAT::System Output] [output/wasapi] [VB-Audio VoiceMeeter VAIO] waiting for start time... now=360000000 start_time=575231000
04/22 21:30:31 Trace: [RAAT::System Output] [output/wasapi] [VB-Audio VoiceMeeter VAIO] waiting for start time... now=460000000 start_time=575231000
04/22 21:30:31 Trace: [RAAT::System Output] [output/wasapi] [VB-Audio VoiceMeeter VAIO] starting playback: now (570000000ns) + ns_per_buf(110000000) = 680000000ns > 575231000ns streamsample=0
04/22 21:31:59 Trace: [RAAT::System Output] [server] [192.168.1.200:60928] read: eof
04/22 21:31:59 Trace: [RAAT::System Output] [server] [192.168.1.200:60928] destroying client
04/22 21:31:59 Trace: [RAAT::System Output] [session] [192.168.1.200:60928] destroying session
04/22 21:31:59 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:60928]  SENT [12] {"status":"Ended"}
04/22 21:31:59 Trace: [RAAT::System Output] [output/wasapi] teardown
04/22 21:31:59 Trace: [RAAT::System Output] [server] [192.168.1.200:59836] accepted connection
04/22 21:31:59 Trace: [RAAT::System Output] [session] [192.168.1.200:59836] created: 0000000026738B50 (0000000026755388)
04/22 21:31:59 Warn: [RAAT::System Output] [server] failed to write to 192.168.1.200:60928: operation canceled. Closing connection.
04/22 21:32:00 Trace: [jsonserver] [192.168.1.200:47566] read: eof
04/22 21:32:00 Trace: [jsonserver] [192.168.1.200:47566] destroying client
04/22 21:32:00 Trace: [RAAT::System Output] [server] [192.168.1.200:59836] read: eof
04/22 21:32:00 Trace: [RAAT::System Output] [server] [192.168.1.200:59836] destroying client
04/22 21:32:00 Trace: [RAAT::System Output] [session] [192.168.1.200:59836] destroying session
04/22 21:32:00 Trace: [jsonserver] [192.168.1.200:42034] accepted connection
04/22 21:32:04 Trace: [jsonserver] [192.168.1.200:42034] GOT[LL] [1] {"request":"enumerate_devices","subscription_id":"0"}
04/22 21:32:04 Trace: [jsonserver] [192.168.1.200:42034] SENT [1] [nonfinal] {"status": "Success", "devices": [{"device_id": "default", "type": "wasapi", "config": {"output": {"type": "wasapi", "device": "default", "name": "System Output"}, "unique_id": "a174d472-1f82-a783-398
04/22 21:32:04 Trace: [jsonserver] [192.168.1.200:42034] GOT[LL] [2] {"request":"enable_device","device_id":"default","subscription_id":"40"}
04/22 21:32:04 Trace: [jsonserver] [192.168.1.200:42034] SENT [2] [nonfinal] {"status": "Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [server] [192.168.1.200:59840] accepted connection
04/22 21:32:04 Trace: [RAAT::System Output] [session] [192.168.1.200:59840] created: 0000000026737D50 (0000000026754D88)
04/22 21:32:04 Trace: [RAAT::System Output] [server] new script with name=raat.dat:base.lua module=base
04/22 21:32:04 Trace: [RAAT::System Output] [session] [192.168.1.200:59840] pre-loading lua module base
04/22 21:32:04 Trace: [RAAT::System Output] [server] [192.168.1.200:59840] SENT[LL] [1] {"status": "Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [server] new script with name=raat.dat:dkjson.lua module=dkjson
04/22 21:32:04 Trace: [RAAT::System Output] [session] [192.168.1.200:59840] pre-loading lua module dkjson
04/22 21:32:04 Trace: [RAAT::System Output] [server] [192.168.1.200:59840] SENT[LL] [1] {"status": "Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [server] new script with name=raat.dat:protocol.lua module=protocol
04/22 21:32:04 Trace: [RAAT::System Output] [session] [192.168.1.200:59840] pre-loading lua module protocol
04/22 21:32:04 Trace: [RAAT::System Output] [server] [192.168.1.200:59840] SENT[LL] [1] {"status": "Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [server] new script with name=raat.dat:roon_tcp.lua module=(null)
04/22 21:32:04 Trace: [RAAT::System Output] [session] [192.168.1.200:59840] executing lua script
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  [roon] clock socket listening on udp 0.0.0.0:64626
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  [roon] audio socket listening on tcp 0.0.0.0:55053
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  [roon] output info {"config": {"type": "wasapi", "device": "default", "name": "System Output"}}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  [roon] volume info {"config": {"type": "wasapi", "device": "default"}}
04/22 21:32:04 Trace: [RAAT::System Output] [server] [192.168.1.200:59840] SENT[LL] [1] {"status": "Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  GOT [2] {"request":"info"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  SENT [2] {"status":"Success","flags":{"has_write_chmap":true},"transport":{"is_supported":false,"info":[]},"info":{"unique_id":"a174d472-1f82-a783-3980-093322c99dc5"},"source_selection":{
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  GOT [3] {"request":"set_client_type","client_type":"Roon"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  SENT [3] {"status":"Success"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  GOT [4] {"request":"subscribe_transport"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  GOT [5] {"request":"subscribe_controls","controller_id":"fc22ad84-9de5-42e5-b4d0-f25c61fbc5ce"}
04/22 21:32:04 Trace: [RAAT::System Output] [lua@000000002690B878] [192.168.1.200:59840]  SENT [5] {"status":"Success","action":"Changed","controls":{"volume":{"step":1.0,"type":"number","value":35,"info":{"config":{"type":"wasapi","device":"default"}},"mute":false,"max":100.0

pfausto · April 23, 2024, 4:12am

Here is an excerpt from the Roon Server log files from one of the times the issue happened - I started playing “Lace” by Christopher Larkin around the 21:30:31 timestamp, playback cut out around 21:31:59, and the client regained my play progress around 21:33:51:

Part 1 (had to split due to length)

04/22 21:30:31 Info: =======================================================================
04/22 21:30:31 Trace: Track             Lace (trackid=Lace,albumid=50:1:16c60512-e24e-4142-9ffb-f2a727984c7b)
04/22 21:30:31 Trace:     Performance   LibraryPerformance[5170549, LibraryWork[22263726, Lace, by LibraryPerformer[18404446, Christopher Larkin]]]
04/22 21:30:31 Info: Track             Lace (trackid=Lace,albumid=50:1:16c60512-e24e-4142-9ffb-f2a727984c7b)
04/22 21:30:31 Info:     Performance   LibraryPerformance[5170549, LibraryWork[22263726, Lace, by LibraryPerformer[18404446, Christopher Larkin]]]
04/22 21:30:31 Trace: [zone Someone-Windows10] Playing 1 Items
04/22 21:30:31 Trace: [zone Someone-Windows10] Selecting Source state=Stopped
04/22 21:30:31 Info: [zone Someone-Windows10] Canceling Pending Sleep
04/22 21:30:31 Trace: [zone Someone-Windows10] Playing TransportItem
04/22 21:30:31 Info: [zone Someone-Windows10] Canceling Pending Sleep
04/22 21:30:31 Trace: [zone Someone-Windows10] Selecting Source state=Stopped
04/22 21:30:31 Trace: [Someone-Windows10] [HighQuality, 24/48 QOBUZ FLAC => 24/48] [1% buf] [LOADING @ 0:00] Lace - Christopher Larkin
04/22 21:30:31 Info: [Someone-Windows10] [zoneplayer] Playing: /mnt/files/Media/Music/Library/Christopher Larkin/Hollow Knight- Silksong (OST Sample)/01 - Lace.flac
04/22 21:30:31 Info: [Someone-Windows10] [zoneplayer]     Open Result (Playing):Result[Status=Success]
04/22 21:30:31 Info: [Someone-Windows10] [zoneplayer] Starting playback
04/22 21:30:31 Trace: [Someone-Windows10] [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=1249072487
04/22 21:30:31 Trace: [System Output] [raatclient] SENT [11]{"request":"setup","format":{"sample_type":"pcm","sample_rate":48000,"bits_per_sample":24,"channels":2,"sample_subtype":"none"}}
04/22 21:30:31 Info: [zone Someone-Windows10] OnPlayFeedback Playing
04/22 21:30:31 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [1% buf] [PLAYING @ 0:00] Lace - Christopher Larkin
04/22 21:30:31 Trace: [prebuffer] ready 163200/480000 (34%) @ 0/166 sec
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [11] {"message":{"signal_path":[{"method":"wasapi_shared","quality":"high","type":"output"}]},"status":"OutputMessage"}
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [11] {"audio_port_tcp":54923,"status":"Success","audio_port":0,"clock_port":64711}
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output State Changed: Idle => Prepared
04/22 21:30:31 Info: [library/albumdetails] found 1 ingroup alternates, 0 other alternates
04/22 21:30:31 Info: [library/albumdetails] Created album details screen for LibraryAlbum[339503, Christopher Larkin - Hollow Knight: Silksong]
04/22 21:30:31 Info: [library/albumdetails] 3e0106462ff8b0ddaa4ba42ae319b52955f8
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] synced to endpoint System Output clock: realtime=10499000 rtt=0us offset=-807584501us delta=-807584501us
04/22 21:30:31 Trace: [System Output] [raatclient] SENT [12]{"request":"stream","stream_id":1249072487,"first_seq":-1,"nak_port":-1,"buffer_size":10,"ready_threshold":0.2}
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [12] {"status":"Buffering"}
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output State Changed: Prepared => Buffering
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] [zoneplayer/raat] selected System Output as clock master
04/22 21:30:31 Debug: [raat/tcpaudiosource] connecting to 192.168.1.188:54923
04/22 21:30:31 Debug: [raat/tcpaudiosource] connected
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [12] {"status":"Ready"}
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output State Changed: Buffering => Ready
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] wait for ready in 34ms
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Adjusting playback start offset from 50ms to 520ms
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Doing 'ASAP' Start since we are just playing to one device
04/22 21:30:31 Trace: [System Output] [raatclient] SENT [13]{"request":"start","min_offset":520000000,"stream_sample":0}
04/22 21:30:31 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output State Changed: Ready => Playing
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [12] {"status":"Playing"}
04/22 21:30:31 Trace: [System Output] [raatclient] GOT [13] {"time":575231000,"status":"Success"}
04/22 21:30:31 Debug: [easyhttp] [17691] POST to https://api.roonlabs.net/browse/1/works/trackCounts?c=qobuz-us&tidal=max returned after 181 ms, status code: 200, request body size: 115 B
04/22 21:30:31 Debug: [easyhttp] [17693] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=174:1:479b1c83-0016-4c52-ac26-df0208f06dc9&type=description&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=qobuz-us&contentPreferences=reallyAvoidMqa&tidal=max returned after 251 ms, status code: 200, request body size: 0 B
04/22 21:30:31 Debug: [easyhttp] [17692] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=62:1:f82f4606-ddb0-4baa-a42a-e319b52955f8&type=review&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=qobuz-us&contentPreferences=reallyAvoidMqa&tidal=max returned after 295 ms, status code: 200, request body size: 0 B
04/22 21:30:31 Debug: [easyhttp] [17694] GET to https://api.roonlabs.net/metadatatext/1/blobs?objectId=191:0:536888&type=biography&sourceLangs=Rovi-albums:en,Wikipedia:en,Rovi-artists:en,Rovi-compositions:en&c=qobuz-us&contentPreferences=reallyAvoidMqa&tidal=max returned after 474 ms, status code: 200, request body size: 0 B
04/22 21:30:31 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 48000/24/2 BitRate=1610 Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-0.3611646953629963 Quality=Enhanced
    Crossfeed Bauer stereo-to-binaural
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality SubType= Model=System Output
------------------------------------------------------------
04/22 21:30:36 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:04/2:46] Lace - Christopher Larkin
04/22 21:30:37 Info: [stats] 29558mb Virtual, 16586mb Physical, 8255mb Managed, 1882 Handles, 145 Threads
04/22 21:30:37 Info: [library] saved recent ProfileId=2403d835-5999-41a2-b3bf-d51424b246da Time=4/23/2024 2:30:37 AM DataType=album Type=long_nav MetadataId=19608638 ContentId=339503 LibraryId=339503 Text= Genre=
04/22 21:30:41 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:09/2:46] Lace - Christopher Larkin
04/22 21:30:46 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:15/2:46] Lace - Christopher Larkin
04/22 21:30:51 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:20/2:46] Lace - Christopher Larkin
04/22 21:30:52 Info: [stats] 29558mb Virtual, 16586mb Physical, 8270mb Managed, 1882 Handles, 145 Threads
04/22 21:30:54 Trace: [airplay] expired device AirPlayDevice[DeviceId=6CB1339B0825@epic109530._raop._tcp.local, Name=epic109530.local, Model=MacBookPro18,3, IPEndPoint=192.168.1.173:7000] because it isn't connected and hasn't been seen in 180s
04/22 21:30:56 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:25/2:46] Lace - Christopher Larkin
04/22 21:31:01 Trace: [Someone-Windows10] [zoneplayer/raat] sync System Output: realtime=30149180833 rtt=0us offset=-807621819us delta=-323us drift=-37318us in 30.176s (-1236.684ppm, -4452.061ms/hr)
04/22 21:31:01 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:30/2:46] Lace - Christopher Larkin
04/22 21:31:04 Debug: [easyhttp] [17695] POST to https://api.roonlabs.net/device-map/1/register returned after 112 ms, status code: 200, request body size: 7 KB
04/22 21:31:04 Trace: [devicemap] device map updated
04/22 21:31:06 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:35/2:46] Lace - Christopher Larkin
04/22 21:31:07 Info: [stats] 29583mb Virtual, 16586mb Physical, 8262mb Managed, 1881 Handles, 148 Threads
04/22 21:31:11 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:40/2:46] Lace - Christopher Larkin
04/22 21:31:16 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:45/2:46] Lace - Christopher Larkin
04/22 21:31:21 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:50/2:46] Lace - Christopher Larkin
04/22 21:31:22 Info: [stats] 29583mb Virtual, 16586mb Physical, 8276mb Managed, 1881 Handles, 148 Threads
04/22 21:31:26 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 0:55/2:46] Lace - Christopher Larkin
04/22 21:31:31 Trace: [Someone-Windows10] [zoneplayer/raat] sync System Output: realtime=60288550500 rtt=0us offset=-807622449us delta=705us drift=-37948us in 60.316s (-629.161ppm, -2264.981ms/hr)
04/22 21:31:31 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:00/2:46] Lace - Christopher Larkin
04/22 21:31:36 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:05/2:46] Lace - Christopher Larkin
04/22 21:31:37 Info: [stats] 29534mb Virtual, 16586mb Physical, 8232mb Managed, 1881 Handles, 142 Threads
04/22 21:31:41 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:10/2:46] Lace - Christopher Larkin
04/22 21:31:46 Trace: [library] endmutation in 143ms
04/22 21:31:47 Trace: [library] endmutation in 196ms
04/22 21:31:47 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:14/2:46] Lace - Christopher Larkin
04/22 21:31:47 Trace: [library] endmutation in 147ms
04/22 21:31:52 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:20/2:46] Lace - Christopher Larkin
04/22 21:31:52 Info: [stats] 30032mb Virtual, 16639mb Physical, 9054mb Managed, 1882 Handles, 204 Threads
04/22 21:31:57 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:25/2:46] Lace - Christopher Larkin
04/22 21:31:59 Info: [transport] destroyed zone Kitchen speaker was playing? False
04/22 21:31:59 Trace: [zone Kitchen speaker] Suspend
04/22 21:31:59 Info: [zone Kitchen speaker] Canceling Pending Sleep
04/22 21:31:59 Info: [zone Kitchen speaker] Canceling Pending Sleep
04/22 21:31:59 Trace: [raat_ll/client] [System Output] no data received for >10000ms. Killing connection.
04/22 21:31:59 Trace: [raatserver] [System Output] lost client connection. Retrying
04/22 21:31:59 Info: [raatserver] [System Output] connecting (attempt 1)
04/22 21:31:59 Info: [transport] destroyed zone Someone-Windows10 was playing? True
04/22 21:31:59 Trace: [zone Someone-Windows10] Suspend
04/22 21:31:59 Info: 
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 48000/24/2 BitRate=1610 Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    VolumeNormaliation GainDb=-0.3611646953629963 Quality=Enhanced
    Crossfeed Bauer stereo-to-binaural
    Truncate FromBitsPerSample=64 ToBitsPerSample=24 Quality=Lossless
    Raat Device=System Output
    Output OutputType=Local_SharedMode_Wasapi Quality=HighQuality SubType= Model=System Output
------------------------------------------------------------
04/22 21:31:59 Info: [zone Someone-Windows10] OnPlayFeedback Stopped
04/22 21:31:59 Info: [zone Someone-Windows10] Canceling Pending Sleep
04/22 21:31:59 Info: [zone Someone-Windows10] Canceling Pending Sleep
04/22 21:31:59 Debug: [raat/tcpaudiosource] disconnecting
04/22 21:31:59 Info: [audio/env] [zoneplayer] All streams were disposed
04/22 21:31:59 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
04/22 21:31:59 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output State Changed: Playing => Prepared
04/22 21:31:59 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
04/22 21:32:00 Info: sleep 393ms after flush
04/22 21:32:00 Info: [transport] destroyed zone Firebolt was playing? False
04/22 21:32:00 Trace: [zone Firebolt] Suspend
04/22 21:32:00 Info: [zone Firebolt] Canceling Pending Sleep
04/22 21:32:00 Info: [zone Firebolt] Canceling Pending Sleep
04/22 21:32:00 Trace: [transport/zonedisplay] [Chromecast-Ultra-c3d8750b62a3cfaab42385b763457290._googlecast._tcp.local] un-associated endpoint
04/22 21:32:00 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
04/22 21:32:00 Trace: [raatserver] [RaatServer SOMEONE-WINDOWS10 @ 192.168.1.188:9200] lost client connection. Retrying
04/22 21:32:00 Trace: [raatserver] [RaatServer SOMEONE-WINDOWS10 @ 192.168.1.188:9200] connecting (attempt 1)
04/22 21:32:00 Trace: [rnet/RnetJsonClient] no data received for >10000ms. Killing connection.
04/22 21:32:00 Trace: [raatserver] [RaatServer someone-ubuntu @ 127.0.0.1:9200] lost client connection. Retrying
04/22 21:32:00 Trace: [raatserver] [RaatServer someone-ubuntu @ 127.0.0.1:9200] connecting (attempt 1)
04/22 21:32:01 Info: [cast] lost device CastDevice[DeviceId=Google-Nest-Mini-a1b87344c3e02544be40075e4a755d36._googlecast._tcp.local, Name=Google Nest Mini, Address=192.168.1.185] because it disconnected
04/22 21:32:02 Info: [transport/zonedisplay] Zone display unregistered: CastDevice[DeviceId=Chromecast-Ultra-c3d8750b62a3cfaab42385b763457290._googlecast._tcp.local, Name=Chromecast Ultra, Address=192.168.1.13]
04/22 21:32:02 Info: [cast] lost device CastDevice[DeviceId=Chromecast-Ultra-c3d8750b62a3cfaab42385b763457290._googlecast._tcp.local, Name=Chromecast Ultra, Address=192.168.1.13] because it disconnected
04/22 21:32:04 Trace: [raatserver] [RaatServer SOMEONE-WINDOWS10 @ 192.168.1.188:9200] connected
04/22 21:32:04 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
04/22 21:32:04 Trace: [raatserver] [RaatServer someone-ubuntu @ 127.0.0.1:9200] connected
04/22 21:32:04 Trace: [rnet/RnetJsonClient] SENT {"request":"enumerate_devices","subscription_id":"0"}
04/22 21:32:04 Info: [cast] lost device CastDevice[DeviceId=Google-Cast-Group-0d8422c4d7ab4b37a24f1540e02c60ef._googlecast._tcp.local, Name=Google Cast Group, Address=192.168.1.185] because it disconnected
04/22 21:32:04 Warn: [raat_ll/client] [System Output] failed to connect Cannot access a disposed object.
Object name: 'System.Net.Sockets.Socket'.
04/22 21:32:04 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": []}
04/22 21:32:04 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success", "devices": [{"device_id": "default", "type": "wasapi", "config": {"output": {"type": "wasapi", "device": "default", "name": "System Output"}, "unique_id": "a174d472-1f82-a783-3980-093322c99dc5", "volume": {"type": "wasapi", "device": "default"}, "external_config": {}}, "name": "System Output", "is_system_output": true, "discovery_data": {"unique_id": "a174d472-1f82-a783-3980-093322c99dc5", "tcp_port": 51333, "version": "N/A", "raat_version": "1.1.39", "vendor": "N/A", "protocol_version": "3", "model": "N/A"}}, {"device_id": "{0.0.0.00000000}.{464c27a7-391a-4984-a32a-3ca21e24b504}", "type": "wasapi", "name": "Realtek(R) Audio"}, {"device_id": "{0.0.0.00000000}.{5259179c-c113-464f-8c94-5dd7ed133dd8}", "type": "wasapi", "name": "NVIDIA High Definition Audio"}, {"device_id": "{0.0.0.00000000}.{7ee7d2f1-0354-43a4-a1ea-3c3eda14df4a}", "type": "wasapi", "name": "Steam Streaming Speakers"}, {"device_id": "{0.0.0.00000000}.{8fd9f1e7-642d-490e-800f-54f5ca6e1664}", "type": "wasapi", "name": "Steam Streaming Microphone"}, {"device_id": "{0.0.0.00000000}.{9107d5f6-8140-415f-8dbd-035bbe4e775e}", "type": "wasapi", "name": "NVIDIA High Definition Audio"}, {"device_id": "{0.0.0.00000000}.{e47adafc-4cd2-42d4-ade6-b15b5bf393cb}", "type": "wasapi", "name": "Yeti Stereo Microphone", "usb_id": "b58e:9e84"}, {"device_id": "{0.0.0.00000000}.{fba58cf6-67ab-4768-8147-c3e0bb47ba41}", "type": "wasapi", "name": "VB-Audio VoiceMeeter VAIO"}, {"device_id": "{0.0.0.00000000}.{fbac11df-52db-48c4-b441-ff65d3e07d26}", "type": "wasapi", "name": "VB-Audio VoiceMeeter AUX VAIO"}, {"device_id": "{A80362FF-CE76-4DD9-874A-704C57BF0D6A}", "type": "asio", "name": "Realtek ASIO"}, {"device_id": "{BAEC28F8-10AC-4BDB-AC16-8CE24BBF8E9D}", "type": "asio", "name": "Voicemeeter AUX Virtual ASIO"}, {"device_id": "{368955AF-5482-4057-AB31-D094AF769772}", "type": "asio", "name": "Voicemeeter Insert Virtual ASIO"}, {"device_id": "{9175CF07-885D-46B4-9EA1-4126D6648DE6}", "type": "asio", "name": "Voicemeeter Virtual ASIO"}]}
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::default Type=wasapi Name=System Output 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{464c27a7-391a-4984-a32a-3ca21e24b504} Type=wasapi Name=Realtek(R) Audio 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{5259179c-c113-464f-8c94-5dd7ed133dd8} Type=wasapi Name=NVIDIA High Definition Audio 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{7ee7d2f1-0354-43a4-a1ea-3c3eda14df4a} Type=wasapi Name=Steam Streaming Speakers 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{8fd9f1e7-642d-490e-800f-54f5ca6e1664} Type=wasapi Name=Steam Streaming Microphone 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{9107d5f6-8140-415f-8dbd-035bbe4e775e} Type=wasapi Name=NVIDIA High Definition Audio 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{e47adafc-4cd2-42d4-ade6-b15b5bf393cb} Type=wasapi Name=Yeti Stereo Microphone UsbId=b58e:9e84
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{fba58cf6-67ab-4768-8147-c3e0bb47ba41} Type=wasapi Name=VB-Audio VoiceMeeter VAIO 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{0.0.0.00000000}.{fbac11df-52db-48c4-b441-ff65d3e07d26} Type=wasapi Name=VB-Audio VoiceMeeter AUX VAIO 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{A80362FF-CE76-4DD9-874A-704C57BF0D6A} Type=asio Name=Realtek ASIO 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{BAEC28F8-10AC-4BDB-AC16-8CE24BBF8E9D} Type=asio Name=Voicemeeter AUX Virtual ASIO 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{368955AF-5482-4057-AB31-D094AF769772} Type=asio Name=Voicemeeter Insert Virtual ASIO 
04/22 21:32:04 Info: [raatserver] GOT DEVICE c39aa99e-a8c2-4093-9546-38640864ccd4::{9175CF07-885D-46B4-9EA1-4126D6648DE6} Type=asio Name=Voicemeeter Virtual ASIO 
04/22 21:32:04 Trace: [rnet/RnetJsonClient] SENT {"request":"enable_device","device_id":"default","subscription_id":"40"}
04/22 21:32:04 Info: [raatserver] [System Output] connecting (attempt 1)
04/22 21:32:04 Trace: [rnet/RnetJsonClient] GOT NONFINAL {"status": "Success"}
04/22 21:32:04 Trace: [System Output] [raatclient] Initializing RAAT session
04/22 21:32:04 Trace: [System Output] [raatclient] Loading script raat.dat:base.lua as module base [1088 chars]
04/22 21:32:04 Trace: [System Output] [raatclient] Loading script raat.dat:dkjson.lua as module dkjson [22416 chars]
04/22 21:32:04 Trace: [System Output] [raatclient] Loading script raat.dat:protocol.lua as module protocol [2045 chars]
04/22 21:32:04 Trace: [System Output] [raatclient] Loading script raat.dat:roon_tcp.lua [45404 chars]
04/22 21:32:04 Trace: [System Output] [raatclient] RAAT Session initialized in 7ms
04/22 21:32:04 Trace: [System Output] [raatclient] SENT [2]{"request":"info"}
04/22 21:32:04 Trace: [System Output] [raatclient] SENT [3]{"request":"set_client_type","client_type":"Roon"}
04/22 21:32:05 Trace: [System Output] [raatclient] GOT [2] {"status":"Success","flags":{"has_write_chmap":true},"transport":{"is_supported":false,"info":[]},"info":{"unique_id":"a174d472-1f82-a783-3980-093322c99dc5"},"source_selection":{"is_supported":false,"info":[]},"volume":{"is_supported":true,"info":{"config":{"type":"wasapi","device":"default"}}},"platform":{"os":"windows"},"output":{"supported_formats":"44100/16/1|2|6|8,96000/24/1|2|6|8,88200/24/1|2|6|8,96000/16/1|2|6|8,48000/24/1|2|6|8,192000/16/1|2|6|8,176400/16/1|2|6|8,44100/24/1|2|6|8,48000/16/1|2|6|8,176400/24/1|2|6|8,192000/24/1|2|6|8,88200/16/1|2|6|8","info":{"config":{"device":"default","type":"wasapi","name":"System Output"}}}}
04/22 21:32:05 Trace: [System Output] [raatclient] GOT [3] {"status":"Success"}
04/22 21:32:05 Trace: [raatserver] [System Output] connected
04/22 21:32:05 Info: [transport] creating endpoint for raat device a174d472-1f82-a783-3980-093322c99dc5
04/22 21:32:05 Trace: [System Output] [raatclient] SENT [4]{"request":"subscribe_transport"}
04/22 21:32:05 Trace: [System Output] [raatclient] SENT [5]{"request":"subscribe_controls","controller_id":"fc22ad84-9de5-42e5-b4d0-f25c61fbc5ce"}
04/22 21:32:05 Trace: [dspengine] loaded DSP config {"version":3,"items":[{"type":"■■2b","enabled":true},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}],"headroom_adjustment":{"type":"headroom_adjustment"},"sample_rate_conversion":{"type":"sample_rate_conversion"},"active_preset":"bea8a4fb-0047-41c2-b4ed-8bd802ac280a"}
04/22 21:32:05 Trace: [System Output] [raatclient] GOT [5] {"status":"Success","action":"Changed","controls":{"volume":{"step":1.0,"type":"number","value":35,"info":{"config":{"type":"wasapi","device":"default"}},"mute":false,"max":100.0,"min":0.0}}}
04/22 21:32:05 Trace: [transport/audeze] picking preset lowlatency 
04/22 21:32:05 Trace: [transport/audeze] picked null preset!
04/22 21:32:05 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [PLAYING @ 1:28/2:46] Lace - Christopher Larkin
04/22 21:32:05 Trace: [zone Someone-Windows10] Loading from /var/opt/roon/RoonServer/Database/Core/e427e7fbce6742b2b7495d4bcb966213/transport/zone_160172d474a1821f83a73980093322c99dc5.db
04/22 21:32:05 Trace: [zone Someone-Windows10] Suspend
04/22 21:32:05 Info: [zone Someone-Windows10] Canceling Pending Sleep
04/22 21:32:05 Trace: [Someone-Windows10] [zoneplayer/raat] Endpoint System Output Initial State: Idle
04/22 21:32:05 Info: [transport] created zone Someone-Windows10
04/22 21:32:05 Info: [transport]     with endpoint System Output (23:1:a174d472-1f82-a783-3980-093322c99dc5) [IsAvailable=True]
04/22 21:32:05 Trace: [Someone-Windows10] [HighQuality, 24/48 FLAC => 24/48] [100% buf] [STOPPED @ 0:00] 
04/22 21:32:05 Info: [transport] creating endpoint for cast device 23:1:86e8bb57-aa7b-44e3-a6f2-3bf4307568f9
04/22 21:32:05 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"■■2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
04/22 21:32:05 Trace: [transport/audeze] picking preset lowlatency 
04/22 21:32:05 Trace: [transport/audeze] picked null preset!
04/22 21:32:05 Info: [transport/zonedisplay] Zone display registered: CastDevice[DeviceId=Chromecast-Ultra-c3d8750b62a3cfaab42385b763457290._googlecast._tcp.local, Name=Chromecast Ultra, Address=192.168.1.13]
04/22 21:32:05 Trace: [zone Firebolt] Loading from /var/opt/roon/RoonServer/Database/Core/e427e7fbce6742b2b7495d4bcb966213/transport/zone_160157bbe8867baae344a6f23bf4307568f9.db
04/22 21:32:05 Trace: [zone Firebolt] Suspend
04/22 21:32:05 Info: [zone Firebolt] Canceling Pending Sleep
04/22 21:32:05 Info: [transport] created zone Firebolt
04/22 21:32:05 Info: [transport]     with endpoint Chromecast Ultra (23:1:86e8bb57-aa7b-44e3-a6f2-3bf4307568f9) [IsAvailable=True]
04/22 21:32:05 Trace: [transport/zonedisplay] [Chromecast-Ultra-c3d8750b62a3cfaab42385b763457290._googlecast._tcp.local] auto-associated with endpoint Chromecast Ultra (23:1:86e8bb57-aa7b-44e3-a6f2-3bf4307568f9) [IsAvailable=True]
04/22 21:32:05 Debug: [easyhttp] [17697] GET to https://www.qobuz.com/api.json/0.2/user/login?request_sig=451c60ce891df9dcb9b927e6086b2299&request_ts=1713839506 returned after 18538 ms, status code: 200, request body size: 0 B
04/22 21:32:05 Info: [transport] creating endpoint for cast device 23:1:6fc880af-0753-e3ba-f4a2-0a52e08afa37
04/22 21:32:05 Trace: [dspengine] created new dsp config {"version":3,"items":[{"type":"■■2b","enabled":false},{"type":"parametric_equalizer","enabled":false},{"type":"audeze_presets","enabled":false}]}
04/22 21:32:05 Trace: [transport/audeze] picking preset lowlatency 
04/22 21:32:05 Trace: [transport/audeze] picked null preset!
04/22 21:32:05 Trace: [zone Kitchen speaker] Loading from /var/opt/roon/RoonServer/Database/Core/e427e7fbce6742b2b7495d4bcb966213/transport/zone_1601af80c86f5307bae3f4a20a52e08afa37.db
04/22 21:32:05 Trace: [zone Kitchen speaker] Suspend
04/22 21:32:05 Info: [zone Kitchen speaker] Canceling Pending Sleep
04/22 21:32:05 Info: [transport] created zone Kitchen speaker
04/22 21:32:05 Info: [transport]     with endpoint Google Nest Mini (23:1:6fc880af-0753-e3ba-f4a2-0a52e08afa37) [IsAvailable=True]
04/22 21:32:05 Trace: [library] endmutation in 134ms
04/22 21:32:07 Info: [stats] 30080mb Virtual, 16599mb Physical, 10914mb Managed, 1881 Handles, 209 Threads
04/22 21:32:08 Trace: [library] endmutation in 153ms
04/22 21:32:10 Trace: [library] endmutation in 150ms
04/22 21:32:10 Trace: [library] endmutation in 156ms
04/22 21:32:13 Trace: [library] endmutation in 154ms
04/22 21:32:13 Trace: [library] endmutation in 155ms
04/22 21:32:15 Trace: [library] endmutation in 205ms
04/22 21:32:15 Debug: [easyhttp] [17698] POST to https://api.roonlabs.net/device-map/1/register returned after 245 ms, status code: 200, request body size: 7 KB
04/22 21:32:15 Trace: [devicemap] device map updated
04/22 21:32:20 Trace: [library] endmutation in 161ms
04/22 21:32:21 Trace: [library] endmutation in 160ms
04/22 21:32:22 Info: [stats] 30024mb Virtual, 16593mb Physical, 7303mb Managed, 1880 Handles, 199 Threads
04/22 21:32:22 Trace: [library] endmutation in 161ms
04/22 21:32:23 Trace: [library] endmutation in 163ms
04/22 21:32:24 Trace: [library] endmutation in 160ms
04/22 21:32:25 Trace: [library] endmutation in 160ms
04/22 21:32:26 Trace: [library] endmutation in 165ms
04/22 21:32:27 Trace: [library] endmutation in 159ms
04/22 21:32:30 Trace: [library] endmutation in 164ms
04/22 21:32:32 Trace: [library] endmutation in 165ms
04/22 21:32:35 Trace: [library] endmutation in 139ms
04/22 21:32:36 Trace: [library] endmutation in 132ms
04/22 21:32:37 Trace: [library] endmutation in 138ms
04/22 21:32:37 Info: [stats] 29478mb Virtual, 16603mb Physical, 8612mb Managed, 1880 Handles, 135 Threads
04/22 21:32:40 Trace: [library] endmutation in 180ms
04/22 21:32:41 Trace: [library] endmutation in 170ms
04/22 21:32:42 Trace: [library] endmutation in 169ms
04/22 21:32:43 Trace: [library] endmutation in 168ms
04/22 21:32:44 Trace: [library] endmutation in 169ms
04/22 21:32:46 Trace: [library] endmutation in 172ms
04/22 21:32:49 Trace: [library] endmutation in 170ms
04/22 21:32:50 Trace: [library] endmutation in 170ms
04/22 21:32:52 Info: [stats] 29575mb Virtual, 16595mb Physical, 8266mb Managed, 1880 Handles, 147 Threads
04/22 21:32:53 Trace: [library] endmutation in 169ms
04/22 21:32:55 Trace: [library] endmutation in 168ms
04/22 21:32:56 Trace: [library] endmutation in 168ms
04/22 21:32:56 Trace: [library] endmutation in 172ms
04/22 21:32:58 Trace: [library] endmutation in 171ms
04/22 21:32:59 Trace: [library] endmutation in 171ms
04/22 21:33:00 Trace: [library] endmutation in 175ms
04/22 21:33:02 Trace: [library] endmutation in 175ms
04/22 21:33:04 Trace: [library] endmutation in 177ms
04/22 21:33:04 Trace: [library] endmutation in 168ms
04/22 21:33:07 Trace: [library] endmutation in 178ms

Bruce_Orr · April 23, 2024, 4:59pm

pfausto - Thx for jumping in with ur similar experience. To be sure I don’t start going down the wring rabbit holes in search of a solution:

the links immediately under your post to me represent potential solutions??
the longer list on pages 2 and 3 are read-outs of behaviors that played out related to your issue??

First, I know I’m not nearly as sophisticated as you, and would not have a clue about capturing the info on pages 2 and 3, nor interpreting it! I do appreciate you for it, though.

Ultimately, what was the solution to ur similar problem??

My System
IMac hosts roon. roon server and HQP4
Computer and all devices, zones networked with ethernet
60k tracks. Mostly Tidal

Problem occurs when listening vis the computers built-in speakers

I am out of town so cannot recall which roon output is enabled (could be System Output or one other), nor how it is setup

I believe this issue travels upstream to other zones that capitalize on switches, DAC, etc, but none of them can cause my problem on the host iMac

Thanks again,

Bruce

pfausto · April 23, 2024, 11:51pm

Hi Bruce,

The problem I’m facing is not resolved - the other threads I linked at the bottom of my first post are to reports of similar issues from other Roon users. I wanted to collect similar reports to show that the problem could be more widespread than it might look. I don’t think any of them are resolved yet either.

The lists included in posts 2 and 3 are entries I pulled from Roon’s log files around one of the times I encountered the issue. I’m hoping having that information will help Roon support troubleshoot the problem! This page on Roon’s Knowledge Base helped me find these log files: Accessing Roon/RoonServer Logs

And I think I’m seeing the issue regardless of which zone I’m playing to as well - based on my reading of the logs, Roon Server appears to be losing connection to all clients at the same time, even the RAATServer running on the same machine.

connor · April 29, 2024, 5:28pm

Hi @pfausto,

Thank you for your post. We’ve activated diagnostics for your account.

Your RoonServer machine is reporting multiple different local IP addresses to our servers across several separate subnets. How are you connecting your Roon Server machine to the internet? It seems Roon Server is alternating between multiple available network interfaces. Roon Remotes, endpoints, and ARC are all anticipating RoonServer’s IP address to remain the same.

Please list any network hardware and how it’s connected here. Thanks!

pfausto · April 30, 2024, 6:56pm

Hi Connor, I appreciate the response. The machine that RoonServer is running on only has one hardware network interface. The other IPs you see in that diagnostic log are on virtual interfaces for other services I’m running using Docker containers in bridge mode, as well as a Wireguard VPN server. None of those route to the public internet and Roon (hopefully) shouldn’t be trying to use them for anything.

I will say that I restarted the RoonServer service a couple days ago to see if that would bring the memory usage back down (so far, so good). While I haven’t listened to a ton of music since doing so I also haven’t seen the pausing problem occur again yet. So it’s possible in my case all that was necessary was to “turn it off and back on again”. I’ll keep an ear on it over the next couple days, though, in case it’s something that only occurs when the server has been running for a while.