ASTERISK-26028: WebRTC one sec delay hearing sound

[Home]

Summary: ASTERISK-26028: WebRTC one sec delay hearing sound

Reporter: Sebastian Gutierrez (sum) Labels:

Date Opened: 2016-05-17 06:36:12 Date Closed: 2016-05-18 08:28:23

Priority: Major Regression?

Status: Closed/Complete Components:

Versions: 13.9.0 Frequency of
Occurrence Constant

Related
Issues:

Environment: Attachments:

Description: Using any webrtc sip stack, you can see a delay of one second hearing the sound, the thing here seems to be that asterisk starts sending rtp before the DTLS handshake has been done.

(using chan_sip)

in a simple rtp debug from asterisk side I can see the problem.

== WebSocket connection from '167.56.87.167:50508' for protocol 'sip' accepted using version '13'
-- Registered SIP '1001' at 167.56.87.167:50508
== Using SIP RTP CoS mark 5
== Using UDPTL CoS mark 5
-- Executing [1235@agentes:1] Answer("SIP/1001-00000048", "0") in new stack
-- Executing [1235@agentes:2] Playback("SIP/1001-00000048", "queue-youarenext,") in new stack
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060959, ts 000160, len 000160)
-- <SIP/1001-00000048> Playing 'queue-youarenext.slin' (language 'es')
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060960, ts 000320, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060961, ts 000480, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060962, ts 000640, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060963, ts 000800, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060964, ts 000960, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060965, ts 001120, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060966, ts 001280, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060967, ts 001440, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060968, ts 001600, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060969, ts 001760, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060970, ts 001920, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060971, ts 002080, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060972, ts 002240, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060973, ts 002400, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060974, ts 002560, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060975, ts 002720, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060976, ts 002880, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060977, ts 003040, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060978, ts 003200, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060979, ts 003360, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060980, ts 003520, len 000170)
> 0x7f6c2012f190 -- Probation passed - setting RTP source address to 167.56.87.167:58897
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019928, ts 2210584420, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060981, ts 003680, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019929, ts 2210584580, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060982, ts 003840, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019930, ts 2210584740, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060983, ts 004000, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019931, ts 2210584900, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060984, ts 004160, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019932, ts 2210585060, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060985, ts 004320, len 000170)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060986, ts 004480, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019933, ts 2210585220, len 000160)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019934, ts 2210585380, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060987, ts 004640, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019935, ts 2210585540, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060988, ts 004800, len 000170)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060989, ts 004960, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019936, ts 2210585700, len 000160)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019937, ts 2210585860, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060990, ts 005120, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019938, ts 2210586020, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060991, ts 005280, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019939, ts 2210586180, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060992, ts 005440, len 000170)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060993, ts 005600, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019940, ts 2210586340, len 000160)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019941, ts 2210586500, len 000160)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060994, ts 005760, len 000170)
Sent RTP packet to 167.56.87.167:58897 (via ICE) (type 08, seq 060995, ts 005920, len 000170)
Got RTP packet from 167.56.87.167:58897 (type 08, seq 019942, ts 2210586660, len 000160)
Got RTP packet from 167.56.87.167:58897

Its clear that after the Probation passed is where I can hear the sound and not before this.

Comments: By: Asterisk Team (asteriskteam) 2016-05-17 06:36:13.249-0500

Thanks for creating a report! The issue has entered the triage process. That means the issue will wait in this status until a Bug Marshal has an opportunity to review the issue. Once the issue has been reviewed you will receive comments regarding the next steps towards resolution.

A good first step is for you to review the [Asterisk Issue Guidelines|https://wiki.asterisk.org/wiki/display/AST/Asterisk+Issue+Guidelines] if you haven't already. The guidelines detail what is expected from an Asterisk issue report.

Then, if you are submitting a patch, please review the [Patch Contribution Process|https://wiki.asterisk.org/wiki/display/AST/Patch+Contribution+Process].
By: Joshua C. Colp (jcolp) 2016-05-18 08:28:23.238-0500

Unfortunately Asterisk does not support trickle ICE as of this time, so SDP negotiation has to fully occur before the process can begin. There's no way to prevent this. As this takes time you see the delay mentioned. This is not itself a bug and besides implementing trickle ICE there's no way to improve this.