Open Bug 1481101 Opened Last year Updated 8 months ago

Intermittent test_getUserMedia_audioConstraints_concurrentIframes.html | Error executing test: Error: verifyPlaying timed out timeout/<@ mochitest/head.js:596:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/he

Categories

(Core :: WebRTC, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned, NeedInfo)

References

Details

(Keywords: intermittent-failure, leave-open, Whiteboard: [stockwell disabled])

Attachments

(1 file)

Filed by: btara [at] mozilla.com

https://treeherder.mozilla.org/logviewer.html#?job_id=192136693&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/Mdj-4ZrnS9a8tA53PoVu3g/runs/0/artifacts/public/logs/live_backing.log

[task 2018-08-05T14:37:35.729Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Should have created a testcase for each constraint 
[task 2018-08-05T14:37:35.731Z] 14:37:35     INFO - Buffered messages logged at 14:36:20
[task 2018-08-05T14:37:35.733Z] 14:37:35     INFO - Start loopback tone at 1000
[task 2018-08-05T14:37:35.734Z] 14:37:35     INFO - Buffered messages logged at 14:36:32
[task 2018-08-05T14:37:35.736Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | gUM stream for testCase.e=true.g=false.n=false should have the same constraints as were requested from gUM. Differences:  
[task 2018-08-05T14:37:35.738Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | gUM stream for testCase.e=true.g=true.n=true should have the same constraints as were requested from gUM. Differences:  
[task 2018-08-05T14:37:35.739Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | gUM stream for testCase.e=true.g=false.n=false should not have had constraints altered after all gUM calls are done. Differences:  
[task 2018-08-05T14:37:35.741Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | gUM stream for testCase.e=true.g=true.n=true should not have had constraints altered after all gUM calls are done. Differences:  
[task 2018-08-05T14:37:35.743Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Stream should be a LocalMediaStream 
[task 2018-08-05T14:37:35.744Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Before starting the media element, currentTime = 0 
[task 2018-08-05T14:37:35.746Z] 14:37:35     INFO - TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
[task 2018-08-05T14:37:35.748Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Media element should be playing 
[task 2018-08-05T14:37:35.754Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Duration should be infinity 
[task 2018-08-05T14:37:35.758Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Ready state shall be HAVE_ENOUGH_DATA or HAVE_CURRENT_DATA 
[task 2018-08-05T14:37:35.760Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Seekable length shall be zero 
[task 2018-08-05T14:37:35.762Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Buffered length shall be zero 
[task 2018-08-05T14:37:35.764Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | MediaElement is not seekable with MediaStream 
[task 2018-08-05T14:37:35.768Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Start offset time shall not be a number 
[task 2018-08-05T14:37:35.770Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Loop shall be false 
[task 2018-08-05T14:37:35.772Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Preload should not exist 
[task 2018-08-05T14:37:35.773Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | No src should be defined 
[task 2018-08-05T14:37:35.775Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Current src should still be an empty string 
[task 2018-08-05T14:37:35.778Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Stream should be a LocalMediaStream 
[task 2018-08-05T14:37:35.780Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Before starting the media element, currentTime = 0 
[task 2018-08-05T14:37:35.781Z] 14:37:35     INFO - TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
[task 2018-08-05T14:37:35.783Z] 14:37:35     INFO - Buffered messages finished
[task 2018-08-05T14:37:35.785Z] 14:37:35     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Error executing test: Error: verifyPlaying timed out timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:596:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:596:26 ... verifyPlaying@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:102:12 ... playMediaWithoutStoppingTracks@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:66:12 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:138:13 ... async*runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:476:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:476:10 ... runTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:248:15 ... promise callback*runTest@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:247:31 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1 ...  
[task 2018-08-05T14:37:35.786Z] 14:37:35     INFO -     runTestWhenReady/<@dom/media/tests/mochitest/head.js:478:7
[task 2018-08-05T14:37:35.788Z] 14:37:35     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:476:10
[task 2018-08-05T14:37:35.789Z] 14:37:35     INFO -     runTest/<@dom/media/tests/mochitest/mediaStreamPlayback.js:248:15
[task 2018-08-05T14:37:35.791Z] 14:37:35     INFO -     promise callback*runTest@dom/media/tests/mochitest/mediaStreamPlayback.js:247:31
[task 2018-08-05T14:37:35.793Z] 14:37:35     INFO -     @dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1
[task 2018-08-05T14:37:35.794Z] 14:37:35     INFO - GECKO(3035) | MEMORY STAT | vsize 1713MB | residentFast 181MB | heapAllocated 24MB
[task 2018-08-05T14:37:35.800Z] 14:37:35     INFO - TEST-OK | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | took 108907ms
[task 2018-08-05T14:37:35.801Z] 14:37:35     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Test must leave no active gUM streams behind. 
[task 2018-08-05T14:37:35.803Z] 14:37:35    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2018-08-05T14:37:35.805Z] 14:37:35     INFO - TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | called finish() multiple times
[task 2018-08-05T14:37:35.806Z] 14:37:35     INFO - TEST-INFO 
[task 2018-08-05T14:37:35.808Z] 14:37:35     INFO - GECKO(3035) | ++DOMWINDOW == 17 (0x7f4481274400) [pid = 3103] [serial = 70] [outer = 0x7f4488a34800]
This bug has failed 41 times in the last 7 days. Failures occur on linux platforms and on opt, asand and debug build types.

Log:
https://treeherder.mozilla.org/logviewer.html#?job_id=196010473&repo=autoland&lineNumber=36898

INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Before starting the media element, currentTime = 0 
[task 2018-08-26T23:04:47.935Z] 23:04:47     INFO - TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
[task 2018-08-26T23:04:47.937Z] 23:04:47     INFO - Buffered messages finished
[task 2018-08-26T23:04:47.939Z] 23:04:47     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Error executing test: Error: verifyPlaying timed out timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:37 ... verifyPlaying@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:102:12 ... playMediaWithoutStoppingTracks@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:66:12 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:138:13 ... async*runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:10 ... runTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:248:15 ... promise callback*runTest@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:247:31 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1 ...  
[task 2018-08-26T23:04:47.942Z] 23:04:47     INFO -     runTestWhenReady/<@dom/media/tests/mochitest/head.js:472:7
[task 2018-08-26T23:04:47.943Z] 23:04:47     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:471:6
[task 2018-08-26T23:04:47.945Z] 23:04:47     INFO -     runTest/<@dom/media/tests/mochitest/mediaStreamPlayback.js:248:15
[task 2018-08-26T23:04:47.946Z] 23:04:47     INFO -     promise callback*runTest@dom/media/tests/mochitest/mediaStreamPlayback.js:247:31
[task 2018-08-26T23:04:47.947Z] 23:04:47     INFO -     @dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1
[task 2018-08-26T23:04:47.949Z] 23:04:47     INFO - GECKO(2873) | MEMORY STAT | vsize 522MB | residentFast 162MB | heapAllocated 17MB
[task 2018-08-26T23:04:47.952Z] 23:04:47     INFO - TEST-OK | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | took 80720ms
[task 2018-08-26T23:04:47.955Z] 23:04:47     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Test must leave no active gUM streams behind. 
[task 2018-08-26T23:04:47.956Z] 23:04:47    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2018-08-26T23:04:47.959Z] 23:04:47     INFO - TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | called finish() multiple times
[task 2018-08-26T23:04:47.960Z] 23:04:47     INFO - TEST-INFO 
[task 2018-08-26T23:04:47.964Z] 23:04:47     INFO - GECKO(2873) | ++DOMWINDOW == 21 (0xe5218000) [pid = 2957] [serial = 74] [outer = 0xe840b160]
[task 2018-08-26T23:04:47.967Z] 23:04:47     INFO - TEST-START | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentStreams.html

drno: Can you please take a look at this bug?
Flags: needinfo?(drno)
Whiteboard: [stockwell needswork]
There is a total of 53 failures in the last 7 days, on linux. 

recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=198162622&repo=autoland&lineNumber=33123

[task 2018-09-07T23:22:20.316Z] 23:22:20     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Before starting the media element, currentTime = 0 
[task 2018-09-07T23:22:20.320Z] 23:22:20     INFO - TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts 
[task 2018-09-07T23:22:20.321Z] 23:22:20     INFO - Buffered messages finished
[task 2018-09-07T23:22:20.323Z] 23:22:20     INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Error executing test: Error: verifyPlaying timed out timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:590:37 ... verifyPlaying@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:102:12 ... playMediaWithoutStoppingTracks@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:66:12 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:138:13 ... async*runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:470:10 ... runTest/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:248:15 ... promise callback*runTest@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:247:31 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1 ...  
[task 2018-09-07T23:22:20.324Z] 23:22:20     INFO -     runTestWhenReady/<@dom/media/tests/mochitest/head.js:472:7
[task 2018-09-07T23:22:20.325Z] 23:22:20     INFO -     promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:471:6
[task 2018-09-07T23:22:20.326Z] 23:22:20     INFO -     runTest/<@dom/media/tests/mochitest/mediaStreamPlayback.js:248:15
[task 2018-09-07T23:22:20.328Z] 23:22:20     INFO -     promise callback*runTest@dom/media/tests/mochitest/mediaStreamPlayback.js:247:31
[task 2018-09-07T23:22:20.329Z] 23:22:20     INFO -     @dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html:21:1
[task 2018-09-07T23:22:20.331Z] 23:22:20     INFO - GECKO(2914) | MEMORY STAT | vsize 1711MB | residentFast 168MB | heapAllocated 22MB
[task 2018-09-07T23:22:20.332Z] 23:22:20     INFO - TEST-OK | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | took 186273ms
[task 2018-09-07T23:22:20.333Z] 23:22:20     INFO - TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | Test must leave no active gUM streams behind. 
[task 2018-09-07T23:22:20.339Z] 23:22:20    ERROR - [SimpleTest.finish()] this test already called finish!
[task 2018-09-07T23:22:20.340Z] 23:22:20     INFO - TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_getUserMedia_audioConstraints_concurrentIframes.html | called finish() multiple times
[task 2018-09-07T23:22:20.341Z] 23:22:20     INFO - TEST-INFO 
[task 2018-09-07T23:22:20.343Z] 23:22:20     INFO - GECKO(2914) | [Child 3006, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1357
[task 2018-09-07T23:22:20.344Z] 23:22:20     INFO - GECKO(2914) | [Child 3006, Main Thread] WARNING: NS_ENSURE_SUCCESS(rv, false) failed with result 0x80004005: file /builds/worker/workspace/build/src/docshell/shistory/nsSHistory.cpp, line 1357
[task 2018-09-07T23:22:20.345Z] 23:22:20     INFO - GECKO(2914) | ++DOMWINDOW == 21 (0x7f34c7277000) [pid = 3006] [serial = 74] [outer = 0x7f34ccd56a00]
[task 2018-09-07T23:22:20.346Z] 23:22:20     INFO - GECKO(2914) | JavaScript error: , line 0: InvalidStateError: An attempt was made to use an object that is not, or is no longer, usable

Nils, are there any updates here?
Whiteboard: [stockwell needswork] → [stockwell needswork:owner]
I disabled test_getUserMedia_audioConstraints_concurrentIframes.html because it was frequently failing
Attachment #9009803 - Flags: review?(jmaher)
Comment on attachment 9009803 [details] [diff] [review]
Disabled test_getUserMedia_audioConstraints_concurrentIframes.html for frequent failures

Review of attachment 9009803 [details] [diff] [review]:
-----------------------------------------------------------------

wow, the only thing this does run on is linux/opt
Attachment #9009803 - Flags: review?(jmaher) → review+
Whiteboard: [stockwell disable-recommended] → [stockwell disabled]
Pushed by ebalazs@mozilla.com:
https://hg.mozilla.org/integration/mozilla-inbound/rev/03824f680cea
Disabled test_getUserMedia_audioConstraints_concurrentIframes.html for frequent failures. r=jmaher
Keywords: checkin-needed
You need to log in before you can comment on or make changes to this bug.