1538566 - Intermittent dom/media/tests/mochitest/test_peerConnection_twoVideoStreams.html | Test timed out.

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

#[markdown(off)]
Filed by: ccoroiu [at] mozilla.com

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

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

[task 2019-03-24T16:43:48.792Z] 16:43:48 INFO - TEST-PASS | dom/media/tests/mochitest/test_peerConnection_twoVideoStreams.html | Remote trickle status should start out unknown
[task 2019-03-24T16:43:48.793Z] 16:43:48 INFO - Run step 14: PC_LOCAL_GUM
[task 2019-03-24T16:43:48.793Z] 16:43:48 INFO - Get 2 local streams
[task 2019-03-24T16:43:48.794Z] 16:43:48 INFO - Call getUserMedia for {"video":true}
[task 2019-03-24T16:43:48.795Z] 16:43:48 INFO - Call getUserMedia for {"video":true}
[task 2019-03-24T16:43:48.796Z] 16:43:48 INFO - Buffered messages finished
[task 2019-03-24T16:43:48.806Z] 16:43:48 INFO - TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_peerConnection_twoVideoStreams.html | Test timed out.
[task 2019-03-24T16:43:48.806Z] 16:43:48 INFO - SimpleTest.ok@https://example.com/tests/SimpleTest/SimpleTest.js:275:18
[task 2019-03-24T16:43:48.807Z] 16:43:48 INFO - reportError@https://example.com/tests/SimpleTest/TestRunner.js:121:22
[task 2019-03-24T16:43:48.807Z] 16:43:48 INFO - TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:142:7
[task 2019-03-24T16:43:48.807Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.807Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.808Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.809Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.809Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.809Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.809Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.810Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.810Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.810Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.810Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.810Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.811Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.811Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.811Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.811Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.812Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.812Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.813Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.813Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.813Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.813Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.814Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.814Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.814Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.815Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.815Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.815Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.816Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.816Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.818Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.819Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.819Z] 16:43:48 INFO - setTimeout handler*TestRunner._checkForHangs@https://example.com/tests/SimpleTest/TestRunner.js:163:5
[task 2019-03-24T16:43:48.823Z] 16:43:48 INFO - TestRunner.runTests/<@https://example.com/tests/SimpleTest/TestRunner.js:381:20
[task 2019-03-24T16:43:48.823Z] 16:43:48 INFO - promise callback*TestRunner.runTests@https://example.com/tests/SimpleTest/TestRunner.js:368:50
[task 2019-03-24T16:43:48.824Z] 16:43:48 INFO - RunSet.runtests@https://example.com/tests/SimpleTest/setup.js:201:14
[task 2019-03-24T16:43:48.827Z] 16:43:48 INFO - RunSet.runall@https://example.com/tests/SimpleTest/setup.js:180:12
[task 2019-03-24T16:43:48.829Z] 16:43:48 INFO - hookupTests@https://example.com/tests/SimpleTest/setup.js:273:12
[task 2019-03-24T16:43:48.830Z] 16:43:48 INFO - parseTestManifest@https://example.com/manifestLibrary.js:36:5
[task 2019-03-24T16:43:48.831Z] 16:43:48 INFO - getTestManifest/req.onload@https://example.com/manifestLibrary.js:49:11
[task 2019-03-24T16:43:48.831Z] 16:43:48 INFO - EventHandlerNonNull*getTestManifest@https://example.com/manifestLibrary.js:45:3
[task 2019-03-24T16:43:48.832Z] 16:43:48 INFO - hookup@https://example.com/tests/SimpleTest/setup.js:253:5
[task 2019-03-24T16:43:48.833Z] 16:43:48 INFO - EventHandlerNonNull*@https://example.com/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-03-24T16:43:48.834Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.835Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.837Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.842Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.847Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.853Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.855Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.
[task 2019-03-24T16:43:48.856Z] 16:43:48 INFO - GECKO(2470) | [Child 2518: Main Thread]: E/signaling [main|PeerConnectionImpl] PeerConnectionImpl.cpp:2721: GetStats: Found no pipelines matching selector.

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-03-18&endday=2019-03-24&tree=all

Comment 2

[Intermittent Failures Robot]

4 failures in 3910 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• try: 1
• mozilla-inbound: 1

Platform breakdown:

• linux64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-03-25&endday=2019-03-31&tree=all

Comment 3

[Intermittent Failures Robot]

4 failures in 2843 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 4

Platform breakdown:

• linux64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-04-01&endday=2019-04-07&tree=all

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 3
• mozilla-inbound: 2

Platform breakdown:

• linux64: 8

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-04-08&endday=2019-04-14&tree=all

Comment 5

[Intermittent Failures Robot]

3 failures in 2673 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform breakdown:

• linux64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-04-15&endday=2019-04-21&tree=all

Comment 6

[Intermittent Failures Robot]

18 failures in 3031 pushes (0.006 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 9
• mozilla-inbound: 6
• try: 1
• mozilla-central: 2

Platform breakdown:

• linux64: 18

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-04-22&endday=2019-04-28&tree=all

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-inbound: 3
• autoland: 11

Platform breakdown:

• linux64: 14

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-04-29&endday=2019-05-05&tree=all

Comment 8

[Intermittent Failures Robot]

4 failures in 4705 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-inbound: 1
• autoland: 2
• mozilla-central: 1

Platform breakdown:

• linux64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-05-06&endday=2019-05-12&tree=all

Comment 9

[Intermittent Failures Robot]

6 failures in 4658 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2
• autoland: 4

Platform breakdown:

• linux64: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-05-13&endday=2019-05-19&tree=all

Comment 10

[Andreas Pehrson [:pehrsons]]

Hmm, we have a number of failures here with error "Error in test execution: NotReadableError: Failed to allocate videosource ", like [1], [2], [3].

It happens intermittently after the timeout. Due to navigation? Are we hanging on a video-only-gUM?

[1] https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=247004204&repo=autoland&lineNumber=55314
[2] https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=246882573&repo=autoland&lineNumber=45572
[3] https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=246424929&repo=mozilla-central&lineNumber=46573

Comment 11

[Intermittent Failures Robot]

5 failures in 4730 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• try: 3
• mozilla-central: 1
• mozilla-inbound: 1

Platform breakdown:

• linux64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-05-20&endday=2019-05-26&tree=all

Comment 12

[Intermittent Failures Robot]

3 failures in 4337 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-central: 1

Platform breakdown:

• linux64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-05-27&endday=2019-06-02&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 5
• mozilla-central: 2
• mozilla-inbound: 1
• try: 1

Platform breakdown:

• linux64: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-06-03&endday=2019-06-09&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 2
• mozilla-inbound: 2
• mozilla-beta: 1

Platform breakdown:

• linux64: 9

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-06-10&endday=2019-06-16&tree=all

Comment 15

[Intermittent Failures Robot]

3 failures in 2581 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform breakdown:

• linux64: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-06-17&endday=2019-06-23&tree=all

Comment 16

[Intermittent Failures Robot]

5 failures in 4409 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 2
• mozilla-inbound: 2
• mozilla-central: 1

Platform breakdown:

• linux64: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-06-24&endday=2019-06-30&tree=all

Comment 17

[Intermittent Failures Robot]

4 failures in 3501 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 1
• autoland: 3

Platform breakdown:

• linux64: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-07-01&endday=2019-07-07&tree=all

Comment 18

[Byron Campen [:bwc]]

This is yet another case of bug gUM_timeout.

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 8
• mozilla-central: 2
• mozilla-inbound: 1

Platform breakdown:

• linux64: 11

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-07-08&endday=2019-07-14&tree=all

Comment 20

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-07-15&endday=2019-07-21&tree=all

Comment 21

[Byron Campen [:bwc]]

Looks like this may have decreased in frequency lately. Looking at the other bugs that depend on gum_timeout, they seem to be pretty rare right now.

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• linux64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1538566&startday=2019-07-22&endday=2019-07-28&tree=all

Comment 23

[Byron Campen [:bwc]]

Another failure due to gum_timeout.

Comment 24

[Andreas Pehrson [:pehrsons]]

(In reply to Byron Campen [:bwc] from comment #23)

Another failure due to gum_timeout.

That one was in release so it makes sense.

This should work much better on linux now, after padenot's work there. I'd guess it was fixed by either bug 1429847 or bug 1567457 (https://github.com/djg/cubeb-pulse-rs/commit/3a748a2df25658f1c8c5a475b9dd2ae4561b174b).

Comment 25

[Paul Adenot (:padenot)]

It's entirely plausible that my patch has improved this one, and probably lots of others.

Comment 26

[Firefox Bug Husbandry Bot]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup