Open Bug 1481101 Opened 6 years ago Updated 2 years 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: Audio/Video, defect, P5)

defect

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

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

I think we're happy to keep this test running on just linux opt so we have some coverage here. Bryce, do you think this is worth keeping around?

Flags: needinfo?(drno) → needinfo?(bvandyk)

I would be happy to keep this around. Echoing my understanding to make sure I'm on the right page, we're still running this in Linux opt and don't appear to be failing, so we have some coverage there?

It would be nice to get coverage back on other builds, but I don't think I'll have cycles to debug this in the near future to dig into this.

Flags: needinfo?(bvandyk)

Yes, it looks like it is running fine on Linux opt.

Component: WebRTC → WebRTC: Audio/Video
Severity: normal → S3
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: