Losing contact with RAAT when I switch Albums

Hi,

Roon plays great through a single album, but when switching to another album in queue or switching to another album via “Play Now” it loses contact with my RAAT endpoint and then skips track to track until it exhausts the queue not playing any sound.

Restarting the Roon client makes it work again but only for a single album. It plays track to track fine within the album, but even auto-advancing to another queued album fails in this way.

I have a separate Roon server on a ubuntu box talking to my Roon client on PC. I don’t think it’s a network issue as when I have an external DAC plugged into my client PC it doesn’t have the problem.

Any suggestions?

The server log shows (don’t mock me for the logitech headset - it’s a standin):

06/14 16:38:52 Trace: [Logi Headset] [HighQuality, 24/96 FLAC => 16/48] [100% buf] [LOADING @ 0:00] Autumn Leaves - Cannonball Adderley / Miles Davis / Joseph Kosma
06/14 16:38:52 Trace: [Logi Headset] [zoneplayer/raat] Endpoint Logitech G930 Headset State Changed: Playing => Prepared
06/14 16:38:52 Trace: [Logitech G930 Headset] [raatclient] SENT [9]{"request":"end_stream"}
06/14 16:38:52 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/14 16:38:52 Debug: [raat/tcpaudiosource] disconnecting
06/14 16:38:52 Info: [audio/env] [zoneplayer] All streams were disposed
06/14 16:38:52 Trace: [Logitech G930 Headset] [raatclient] GOT [7] {"status":"Ended"}
06/14 16:38:52 Trace: [Logitech G930 Headset] [raatclient] GOT [9] {"status":"Success"}
06/14 16:38:52 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/14 16:38:52 Info: [library/albumdetails] Created album details screen for LibraryAlbum[768303, Cannonball Adderley, Miles Davis - Cannonball & Miles]
06/14 16:38:52 Info: [library/albumdetails] 79004d5730303032353130323138, be0034333131373734
06/14 16:38:52 Trace: [dbperf] flush 0 bytes, 0 ops in 2 ms (cumulative 517454076 bytes, 426924 ops in 58852 ms)
06/14 16:38:52 Trace: [dbperf] flush 0 bytes, 0 ops in 6 ms (cumulative 517454076 bytes, 426924 ops in 58858 ms)
06/14 16:38:52 Info: sleep 100ms after flush
06/14 16:38:52 Info: [Logi Headset] [zoneplayer] Playing: /Music/HR-FLAC/Cannonball Adderley/Somethin' Else/01-Autumn Leaves.flac
06/14 16:38:52 Info: [Logi Headset] [zoneplayer] Queueing: /Music/HR-FLAC/Cannonball Adderley/Somethin' Else/02-Love for Sale.flac
06/14 16:38:53 Info: [Logi Headset] [zoneplayer]     Open Result (Playing):Result[Status=Success]
06/14 16:38:53 Info: [Logi Headset] [zoneplayer] Starting playback
06/14 16:38:53 Trace: [Logi Headset] [zoneplayer/raat] StartStream Sooloos.Broker.Transport.RaatZonePlayer+Endpoint: StreamFormat(channels=2, bitspersample=24, samplerate=192000, isdts=False) => StreamFormat(channels=2, bitspersample=16, samplerate=48000, isdts=False) streamid=614944424
06/14 16:38:53 Info: [zone Logi Headset] OnPlayFeedback Playing
06/14 16:38:53 Trace: [Logi Headset] [HighQuality, 24/192 FLAC => 16/48] [100% buf] [PLAYING @ 0:00] Autumn Leaves - Cannonball Adderley / Miles Davis / Joseph Kosma
06/14 16:38:53 Info: [Logi Headset] [zoneplayer] Open result (Queueing): Result[Status=Success]
06/14 16:38:53 Trace: [prebuffer] ready 652800/1920000 (34%) @ 0/658 sec
06/14 16:38:53 Info:
--[ SignalPath ]---------------------------------------------
SignalPath Quality = HighQuality
Elements:
    Source Format=Flac 192000/24/2 BitRate=5202 Quality=Lossless
    UpgradeBitDepth FromBitsPerSample=24 ToBitsPerSample=64 Quality=Lossless
    SampleRateConversion FromSampleRate=192000 ToSampleRate=48000 Algorithm=HighQuality Quality=HighQuality
    Truncate FromBitsPerSample=64 ToBitsPerSample=16 Quality=HighQuality
    Raat Device=Logitech G930 Headset
    Output OutputType=Local_ExclusiveMode_Wasapi Quality=Lossless SubType= Model=Logitech G930 Headset
------------------------------------------------------------
**06/14 16:38:54 Warn: [Logi Headset] [zoneplayer/raat] failed to sync sender clock to endpointLogitech G930 Headset: Sooloos.Broker.Transport.ClockSyncResult**
06/14 16:38:54 Warn: [Logi Headset] [zoneplayer/raat] failed to sync clocks with any endpoints..giving up
06/14 16:38:54 Info: [audio/env] [zoneplayer -> stream -> endpoint] All streams were disposed
06/14 16:38:54 Info: [audio/env] [zoneplayer -> stream] All streams were disposed
06/14 16:38:54 Info: sleep 100ms after flush
06/14 16:38:54 Warn: Track Stopped Due to Error

The client shows:
06/14 09:38:29 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  Doing ASAP start based on current device clock (min_offset=120000000)
06/14 09:38:29 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  SENT [7] {"status":"Playing"}
06/14 09:38:29 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  SENT [8] {"status":"Success","time":768217000}
06/14 09:38:29 Trace: [RAAT::Logitech G930 Headset] [output/wasapi] [2- Logitech G930 Headset] starting playback: now (800000000ns) + ns_per_buf(100000000) = 900000000ns > 768217000ns streamsample=0
06/14 09:38:53 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  GOT [9] {"request":"end_stream"}
06/14 09:38:53 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  SENT [7] {"status":"Ended"}
06/14 09:38:53 Trace: [RAAT::Logitech G930 Headset] [lua@000000001F7A83D8] [192.168.50.150:44632]  SENT [9] {"status":"Success"}

Hi @Robert_Greenwalt,

Thanks for reaching out and for posting the appropriate log snippets, this is very helpful! Is there any change in behavior if you try to disable Exclusive Mode or Event Mode for this zone under it’s Device Setup options? Device Setup can be accessed in Roon Settings -> Audio - > Cogwheel icon next to zone

:frowning:
I should have verified it reproduced today… I jumped in and switched off Event Mode and it worked great. I then turned Event Mode back on to verify it’s the cause but now I can’t get it to repro at all!

sigh.

A fix is good, but verifying the cause is even better and I’m sorry I can’t do that for you today. I’ll try rebooting the whole system (event mode is back on) and see if I can isolate it for you.

Thanks for the suggestion and support.

Robert

Hmm - just repro’d. It may be I have to let it get into the track a bit before I ask for a change.

Yup - If I switch albums < 10s it works fine. Not sure exactly what the threshold is… 5-6s seems good and 30s and 11s just failed.

Turning off both Event Mode and Exclusive Mode had no effect. When I got at least 20s into an album, switching albums caused it to fail playback on everything after that until I restart the app.

Hi @Robert_Greenwalt,

Thanks for trying to reproduce further. Does this issue occur only when the Logitech headphones are used or does it occur for other zones as well after waiting 20 seconds? Can you let me know the exact local time + date + track you next attempt to reproduce and run into this issue?

I don’t recall hitting it when using an external dac, but that’s not available right now. I can try headphones into the motherboard audio and see if that reproduces this.

I’m not sure what you mean by exact local time/date/track - it seems to be manual track change after the current track gets going (about 20s in). I mean if I change tracks quickly keeping the play time of a track <10s it seems to work ok but if I get >10s in even switching to a track in the same album causes this problem.

Do you want me to repro and give you the logs + time of the change?

Also, the queued transitions seem to work only within the current album - finishing the album and transitioning to another via the queue generates this problem.

Hi @Robert_Greenwalt,

Yes, please reproduce and let me know the timestamp + track.
Once I have this info I can enable diagnostics mode for your account.

Sorry about the delay - life intrudes sometimes.

For tonights test I started with Adelle - Hello until about 8:32 when I switched to Muse - Supremacy. These were both triggered by the “Play Now” button on the album page.

The logs are long so sharing as a google doc.

Let me know if you need anything else.

Thanks
Robert

1 Like

Thanks for the logs @Robert_Greenwalt, let me get these over to the team for analysis and once that’s been completed, we will reach out once more, thanks!

Hello @Robert_Greenwalt,

Thanks for your patience while we investigated this issue.

It appears that Logitech USB headsets have issues when used with WASAPI. Here’s a similar report.

Can you try streaming to your “System Output” zone in Roon, and then selecting the Logitech headset as your system audio device in the Windows settings?

-John

Hey John,

TL;DR - it was user error in firewall rules

I did a bunch of tests and it had nothing to do with the audio device I used.

I put all devices on the same subnet and the problem went away.
I put them back on different subets and got the problem back, so I spent a weekend or so capturing packets. Some UDP packets were getting dropped in the router and I couldn’t understand why. I removed the rules and it worked fine, so I knew it was me, not you. :slight_smile:

Looking through my rule in ubiquiti UI, I relaized that despite appling to both tcp and UDP protos, I had checked the “New” (as in, new, established, invalid) which AFAIK can only apply to a stateful proto like tcp… on a hunch I cleared this check and it all works fine.

Sorry for the false alarm and thanks for taking the time looking at this.for me.

Happlily listening,
Robert

1 Like

This topic was automatically closed 36 hours after the last reply. New replies are no longer allowed.

Hi @Robert_Greenwalt,

Thanks for the update here and for letting us know that the different subnets were the issue and that resolving this also resolved the issues you were having with Roon!