Closed Bug 1593688 Opened 6 years ago Closed 4 years ago

Intermittent /webrtc/RTCDTMFSender-ontonechange-long.https.html | insertDTMF with duration greater than 6000 should be clamped to 6000 - assert_between_inclusive: Expect tonechange event for "" to be fired approximately after 6070 milliseconds expected

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

RESOLVED INCOMPLETE

People

(Reporter: intermittent-bug-filer, Unassigned)

Details

(Keywords: intermittent-failure)

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=274381546&repo=autoland
Full log: https://queue.taskcluster.net/v1/task/KEZcxVosRk-g2JUs23aTvQ/runs/0/artifacts/public/logs/live_backing.log


[task 2019-11-04T14:14:22.247Z] 14:14:22 INFO - TEST-START | /webrtc/RTCDTMFSender-ontonechange-long.https.html
[task 2019-11-04T14:14:22.247Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: D/mtransport Flow[transport_0(none)]; Layer[dtls]: Lower layer is now open; starting TLS
[task 2019-11-04T14:14:22.249Z] 14:14:22 INFO - PID 3120 | (ice/INFO) ICE-PEER(PC:1572876861953275 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html):default): all checks completed success=1 fail=0
[task 2019-11-04T14:14:22.250Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-11-04T14:14:22.254Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: D/mtransport NrIceCtx(PC:1572876861953275 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html)): state 1->2
[task 2019-11-04T14:14:22.254Z] 14:14:22 INFO - PID 3120 | (stun/INFO) STUN-CLIENT(2QfG|IP4:10.49.56.210:56844/UDP|IP4:10.49.56.210:52925/UDP(host(IP4:10.49.56.210:56844/UDP)|candidate:0 1 UDP 2122252543 10.49.56.210 52925 typ host)): Received response; processing
[task 2019-11-04T14:14:22.254Z] 14:14:22 INFO - PID 3120 | (ice/INFO) ICE-PEER(PC:1572876861967996 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html):default)/CAND-PAIR(2QfG): setting pair to state SUCCEEDED: 2QfG|IP4:10.49.56.210:56844/UDP|IP4:10.49.56.210:52925/UDP(host(IP4:10.49.56.210:56844/UDP)|candidate:0 1 UDP 2122252543 10.49.56.210 52925 typ host)
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | (stun/INFO) STUN-CLIENT(oNlZ|IP4:10.49.56.210:52925/UDP|IP4:10.49.56.210:56844/UDP(host(IP4:10.49.56.210:52925/UDP)|prflx)): Received response; processing
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | (ice/INFO) ICE-PEER(PC:1572876861953275 (id=17179869186 url=https://web-platform.test:8443/webrtc/RTCDTMFSender-insertDTMF.https.html):default)/CAND-PAIR(oNlZ): setting pair to state SUCCEEDED: oNlZ|IP4:10.49.56.210:52925/UDP|IP4:10.49.56.210:56844/UDP(host(IP4:10.49.56.210:52925/UDP)|prflx)
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Handshake would have blocked
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: D/mtransport Created SRTP flow!
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: ****** SSL handshake completed ******
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: I/mtransport Flow[transport_0(none)]; Layer[dtls]: Selected ALPN string: webrtc
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - PID 3120 | [Child 3129: Socket Thread]: D/mtransport Created SRTP flow!
[task 2019-11-04T14:14:22.262Z] 14:14:22 INFO - Closing window 32

[task 2019-11-04T14:14:28.437Z] 14:14:28 INFO - TEST-UNEXPECTED-FAIL | /webrtc/RTCDTMFSender-ontonechange-long.https.html | insertDTMF with duration greater than 6000 should be clamped to 6000 - assert_between_inclusive: Expect tonechange event for "" to be fired approximately after 6070 milliseconds expected a number greater than or equal to 6070 and less than or equal to 10070 but got 5157
[task 2019-11-04T14:14:28.437Z] 14:14:28 INFO - test_tone_change_events/</allEventsReceived</onToneChange<@https://web-platform.test:8443/webrtc/RTCDTMFSender-helper.js:123:33
[task 2019-11-04T14:14:28.437Z] 14:14:28 INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1908:25
[task 2019-11-04T14:14:28.437Z] 14:14:28 INFO - Test.prototype.step_func/<@https://web-platform.test:8443/resources/testharness.js:1932:35

Status: NEW → RESOLVED
Closed: 6 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE

This is still happening.

Recent failure: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=290629403&repo=autoland&lineNumber=7240

[task 2020-02-26T20:13:15.983Z] 20:13:15     INFO - TEST-START | /webrtc/RTCDTMFSender-ontonechange-long.https.html
[task 2020-02-26T20:13:15.999Z] 20:13:15     INFO - Closing window 27
[task 2020-02-26T20:13:28.154Z] 20:13:28     INFO - 
[task 2020-02-26T20:13:28.154Z] 20:13:28     INFO - TEST-UNEXPECTED-FAIL | /webrtc/RTCDTMFSender-ontonechange-long.https.html | insertDTMF with duration greater than 6000 should be clamped to 6000 - assert_between_inclusive: Expect tonechange event for "" to be fired approximately after 6070 milliseconds expected a number greater than or equal to 6070 and less than or equal to 10070 but got 11704
[task 2020-02-26T20:13:28.154Z] 20:13:28     INFO - test_tone_change_events/</allEventsReceived</onToneChange<@https://web-platform.test:8443/webrtc/RTCDTMFSender-helper.js:123:33
[task 2020-02-26T20:13:28.154Z] 20:13:28     INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1926:25
[task 2020-02-26T20:13:28.154Z] 20:13:28     INFO - Test.prototype.step_func/<@https://web-platform.test:8443/resources/testharness.js:1951:35
[task 2020-02-26T20:13:28.483Z] 20:13:28     INFO - TEST-OK | /webrtc/RTCDTMFSender-ontonechange-long.https.html | took 12498ms
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 6 years ago6 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 6 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: REOPENED → RESOLVED
Closed: 5 years ago5 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 5 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.