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

Status: RESOLVED DUPLICATE of bug 1420640

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=177567946&repo=autoland

https://queue.taskcluster.net/v1/task/cQHPTrrbTMSoSz6-ao2a1A/runs/0/artifacts/public/logs/live_backing.log

[task 2018-05-09T01:13:58.489Z] 01:13:58     INFO - TEST-START | /webrtc/RTCDTMFSender-ontonechange-long.https.html
[task 2018-05-09T01:13:58.599Z] 01:13:58     INFO - PID 21206 | [GFX1-]: Unknown pipeline used for iframe IframeDisplayItem { clip_id: Clip(4, PipelineId(1, 1)), pipeline_id: PipelineId(1, 10) }
[task 2018-05-09T01:13:58.600Z] 01:13:58     INFO - PID 21206 | ERROR 2018-05-09T01:13:58Z: webrender::display_list_flattener: Unknown pipeline used for iframe IframeDisplayItem { clip_id: Clip(4, PipelineId(1, 1)), pipeline_id: PipelineId(1, 10) }
[task 2018-05-09T01:13:58.617Z] 01:13:58     INFO - PID 21206 |  WARN 2018-05-09T01:13:58Z: webrender::render_backend: Built the scene several times without rendering it.
[task 2018-05-09T01:13:58.694Z] 01:13:58     INFO - PID 21206 | ++DOCSHELL 0x7f6a590c6000 == 5 [pid = 21273] [id = {b46d70ae-b96f-42f7-bd45-ec977f1ab4c3}]
[task 2018-05-09T01:13:58.694Z] 01:13:58     INFO - PID 21206 | ++DOMWINDOW == 20 (0x7f6a5a403800) [pid = 21273] [serial = 30] [outer = (nil)]
[task 2018-05-09T01:13:58.722Z] 01:13:58     INFO - PID 21206 | ++DOMWINDOW == 21 (0x7f6a58819800) [pid = 21273] [serial = 31] [outer = 0x7f6a5a403800]
[task 2018-05-09T01:13:58.892Z] 01:13:58     INFO - PID 21206 | ++DOMWINDOW == 22 (0x7f6a5881bc00) [pid = 21273] [serial = 32] [outer = 0x7f6a5a403800]
[task 2018-05-09T01:13:59.296Z] 01:13:59     INFO - PID 21206 | [Parent 21206, Main Thread] WARNING: NS_ENSURE_TRUE(aSecondURI) failed: file /builds/worker/workspace/build/src/dom/base/ThirdPartyUtil.cpp, line 98
[task 2018-05-09T01:13:59.718Z] 01:13:59     INFO - 
[task 2018-05-09T01:13:59.718Z] 01:13:59     INFO - TEST-UNEXPECTED-FAIL | /webrtc/RTCDTMFSender-ontonechange-long.https.html | insertDTMF with duration greater than 6000 should be clamped to 6000 - assert_approx_equals: Expect tonechange event for "A" to be fired approximately after 0 milliseconds expected 0 +/- 250 but got 307
[task 2018-05-09T01:13:59.718Z] 01:13:59     INFO - test_tone_change_events/</</onToneChange<@https://web-platform.test:8443/webrtc/RTCDTMFSender-helper.js:112:9
[task 2018-05-09T01:13:59.718Z] 01:13:59     INFO - Test.prototype.step@https://web-platform.test:8443/resources/testharness.js:1490:20
[task 2018-05-09T01:13:59.718Z] 01:13:59     INFO - Test.prototype.step_func/<@https://web-platform.test:8443/resources/testharness.js:1514:20
[task 2018-05-09T01:13:59.719Z] 01:13:59     INFO - TEST-OK | /webrtc/RTCDTMFSender-ontonechange-long.https.html | took 1232ms
[task 2018-05-09T01:14:00.061Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 21 (0x7f6a641e6400) [pid = 21273] [serial = 14] [outer = (nil)] [url = http://web-platform.test:8000/webrtc/RTCConfiguration-iceCandidatePoolSize.html]
[task 2018-05-09T01:14:00.061Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 20 (0x7f6a641e3800) [pid = 21273] [serial = 17] [outer = (nil)] [url = http://web-platform.test:8000/webrtc/RTCConfiguration-iceServers.html]
[task 2018-05-09T01:14:00.568Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7fe6a1613000 == 4 [pid = 21206] [id = {4e6c4923-49aa-41f3-b0c4-ef7eaddde8bb}]
[task 2018-05-09T01:14:00.568Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7fe689cdb800 == 3 [pid = 21206] [id = {495585db-2d35-4daa-99e4-82db131cc693}]
[task 2018-05-09T01:14:00.569Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7fe69f0da000 == 2 [pid = 21206] [id = {fa73fb71-d1ed-48f0-a642-d58fb23922b5}]
[task 2018-05-09T01:14:00.572Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 10 (0x7fe687b77c00) [pid = 21206] [serial = 8] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.613Z] 01:14:00     INFO - PID 21206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-05-09T01:14:00.613Z] 01:14:00     INFO - PID 21206 | [Child 21324, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp, line 805
[task 2018-05-09T01:14:00.614Z] 01:14:00     INFO - PID 21206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-05-09T01:14:00.615Z] 01:14:00     INFO - PID 21206 | [Child 21324, Main Thread] WARNING: '!gThread', file /builds/worker/workspace/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
[task 2018-05-09T01:14:00.678Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7fe67c4b0800 == 1 [pid = 21206] [id = {e40edf69-1e8b-4227-8bf3-e9178099cf7c}]
[task 2018-05-09T01:14:00.686Z] 01:14:00     INFO - PID 21206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-05-09T01:14:00.701Z] 01:14:00     INFO - PID 21206 | [Child 21273, Main Thread] WARNING: NS_ENSURE_TRUE(maybeContext) failed: file /builds/worker/workspace/build/src/xpcom/threads/nsThread.cpp, line 805
[task 2018-05-09T01:14:00.702Z] 01:14:00     INFO - PID 21206 | [Child 21273, Main Thread] WARNING: NS_ENSURE_TRUE(thread) failed: file /builds/worker/workspace/build/src/netwerk/base/nsSocketTransportService2.cpp, line 218
[task 2018-05-09T01:14:00.703Z] 01:14:00     INFO - PID 21206 | [Child 21273, Main Thread] WARNING: NS_ENSURE_TRUE(thread) failed: file /builds/worker/workspace/build/src/netwerk/base/nsSocketTransportService2.cpp, line 218
[task 2018-05-09T01:14:00.704Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 19 (0x7f6a600bc000) [pid = 21273] [serial = 24] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.706Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 18 (0x7f6a600b8c00) [pid = 21273] [serial = 21] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.716Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 17 (0x7f6a641c5400) [pid = 21273] [serial = 19] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.717Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 16 (0x7f6a65e06000) [pid = 21273] [serial = 18] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.718Z] 01:14:00     INFO - PID 21206 | --DOMWINDOW == 15 (0x7f6a641c1800) [pid = 21273] [serial = 16] [outer = (nil)] [url = about:blank]
[task 2018-05-09T01:14:00.719Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7f6a6020c800 == 4 [pid = 21273] [id = {1679d87a-92e3-4041-9da8-811f9eedce86}]
[task 2018-05-09T01:14:00.719Z] 01:14:00     INFO - PID 21206 | --DOCSHELL 0x7f6a5fed6000 == 3 [pid = 21273] [id = {c3fc13bc-2c92-457c-a7d8-585ea2099fdf}]
[task 2018-05-09T01:14:00.720Z] 01:14:00     INFO - PID 21206 | [Child 21324, Main Thread] WARNING: '!gThread', file /builds/worker/workspace/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
[task 2018-05-09T01:14:00.721Z] 01:14:00     INFO - PID 21206 | [Child 21324, Main Thread] WARNING: '!gThread', file /builds/worker/workspace/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
[task 2018-05-09T01:14:00.737Z] 01:14:00     INFO - PID 21206 | [Child 21324, Main Thread] WARNING: '!gThread', file /builds/worker/workspace/build/src/xpcom/threads/nsTimerImpl.cpp, line 399
[task 2018-05-09T01:14:00.758Z] 01:14:00     INFO - PID 21206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-05-09T01:14:00.759Z] 01:14:00     INFO - PID 21206 | ###!!! [Parent][RunMessage] Error: Channel closing: too late to send/recv, messages will be lost
[task 2018-05-09T01:14:00.776Z] 01:14:00     INFO - PID 21206 | nsStringStats

Comment 1

[Intermittent Failures Robot]

2 failures in 660 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1460165&startday=2018-05-07&endday=2018-05-13&tree=trunk

Comment 2

[Intermittent Failures Robot]

2 failures in 639 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1
* autoland: 1

Platform breakdown:
* linux64-qr: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1460165&startday=2018-05-14&endday=2018-05-20&tree=trunk

Comment 4

[Intermittent Failures Robot]

1 failures in 632 pushes (0.002 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1460165&startday=2018-05-21&endday=2018-05-27&tree=trunk