1447598 - Intermittent dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Error executing test: Error: verifyPlaying timed out timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:572:63

Status: RESOLVED INCOMPLETE

(Core :: WebRTC, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ncsoregi [at] mozilla.com

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

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

07:53:28     INFO -  1432 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Stream should be a LocalMediaStream
07:53:28     INFO -  1433 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Before starting the media element, currentTime = 0
07:53:28     INFO -  1434 INFO TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts
07:53:28     INFO -  Buffered messages finished
07:53:28    ERROR -  1435 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Error executing test: Error: verifyPlaying timed out timeout/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:572:63 ... promise callback*timeout@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:572:26 ... verifyPlaying@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:102:12 ... value@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:175:14 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:24:16 ... promise callback*@http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:19:12 ... runTestWhenReady/<@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:452:41 ... promise callback*runTestWhenReady@http://mochi.test:8888/tests/dom/media/tests/mochitest/head.js:452: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_stopVideoAudioStreamWithFollowupVideoAudio.html:18:3 ...
07:53:28     INFO -      runTestWhenReady/<@dom/media/tests/mochitest/head.js:454:7
07:53:28     INFO -      Async*runTestWhenReady@dom/media/tests/mochitest/head.js:452:10
07:53:28     INFO -      runTest/<@dom/media/tests/mochitest/mediaStreamPlayback.js:248:15
07:53:28     INFO -      promise callback*runTest@dom/media/tests/mochitest/mediaStreamPlayback.js:247:31
07:53:28     INFO -      @dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:18:3
07:53:28     INFO -  GECKO(5888) | MEMORY STAT | vsize 2098671MB | vsizeMaxContiguous 128978109MB | residentFast 125MB | heapAllocated 16MB
07:53:28     INFO -  1436 INFO TEST-OK | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | took 60448ms
07:53:28     INFO -  1437 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Test must leave no active gUM streams behind.
07:53:28     INFO -  1438 ERROR [SimpleTest.finish()] this test already called finish!
07:53:28    ERROR -  1439 INFO TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | called finish() multiple times
07:53:28     INFO -  TEST-INFO
07:53:28     INFO -  GECKO(5888) | ++DOMWINDOW == 5 (000002F530314000) [pid = 1768] [serial = 120] [outer = 000002F52F62E400]
07:53:28     INFO -  1440 INFO None1441 INFO TEST-START | dom/media/tests/mochitest/test_getUserMedia_stopVideoStream.html
07:53:28     INFO -  GECKO(5888) | ++DOMWINDOW == 6 (000002F530314C00) [pid = 1768] [serial = 121] [outer = 000002F52F62E400]
07:53:28     INFO -  GECKO(5888) | TEST DEVICES: No test devices found (in media.{audio,video}_loopback_dev, using fake streams.
07:53:28     INFO -  GECKO(5888) | --DOMWINDOW == 5 (000002F530314000) [pid = 1768] [serial = 120] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/SimpleTest/iframe-between-tests.html]
07:53:28     INFO -  GECKO(5888) | --DOMWINDOW == 4 (000002F530317C00) [pid = 1768] [serial = 119] [outer = 0000000000000000] [url = http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html]
07:53:28     INFO -  GECKO(5888) | MEMORY STAT | vsize 2098673MB | vsizeMaxContiguous 128978109MB | residentFast 125MB | heapAllocated 16MB
07:53:28     INFO -  1442 INFO TEST-OK | dom/media/tests/mochitest/test_getUserMedia_stopVideoStream.html | took 474ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 2

Platform breakdown:
* windows10-64: 2

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

Comment 2

[Firefox Bug Husbandry Bot]

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

Comment 3

[Cristina Coroiu [:ccoroiu]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=187796678&repo=mozilla-central&lineNumber=14173

:14:34     INFO -  1084 INFO TEST-START | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html
11:14:34     INFO -  GECKO(6344) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
11:14:34     INFO -  GECKO(6344) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
11:14:34     INFO -  GECKO(6344) | TEST DEVICES: No test device found in media.audio_loopback_dev, using fake audio streams.
11:14:34     INFO -  GECKO(6344) | TEST DEVICES: No test device found in media.video_loopback_dev, using fake video streams.
11:15:34     INFO -  TEST-INFO | started process screenshot
11:15:35     INFO -  TEST-INFO | screenshot: exit 0
11:15:35     INFO -  Buffered messages logged at 11:14:34
11:15:35     INFO -  1085 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | A valid string reason is expected
11:15:35     INFO -  1086 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Reason cannot be empty
11:15:35     INFO -  1087 INFO Call getUserMedia for {"video":true,"audio":true}
11:15:35     INFO -  1088 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | One audio track shall be present
11:15:35     INFO -  1089 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track kind should be audio
11:15:35     INFO -  1090 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track id should be defined
11:15:35     INFO -  1091 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track should not be muted
11:15:35     INFO -  1092 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | One video track shall be present
11:15:35     INFO -  1093 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track kind should be video
11:15:35     INFO -  1094 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track id should be defined
11:15:35     INFO -  1095 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Track should not be muted
11:15:35     INFO -  1096 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Stream should be a LocalMediaStream
11:15:35     INFO -  1097 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Before starting the media element, currentTime = 0
11:15:35     INFO -  1098 INFO TEST-FAIL | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | The author of the test has indicated that flaky timeouts are expected.  Reason: WebRTC inherently depends on timeouts
11:15:35     INFO -  Buffered messages finished
11:15:35    ERROR -  1099 INFO TEST-UNEXPECTED-FAIL | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.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 ... value@http://mochi.test:8888/tests/dom/media/tests/mochitest/mediaStreamPlayback.js:175:14 ... @http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:24:16 ... promise callback*@http://mochi.test:8888/tests/dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:19:12 ... 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_stopVideoAudioStreamWithFollowupVideoAudio.html:18:3 ...
11:15:35     INFO -      runTestWhenReady/<@dom/media/tests/mochitest/head.js:478:7
11:15:35     INFO -      promise callback*runTestWhenReady@dom/media/tests/mochitest/head.js:476:10
11:15:35     INFO -      runTest/<@dom/media/tests/mochitest/mediaStreamPlayback.js:248:15
11:15:35     INFO -      promise callback*runTest@dom/media/tests/mochitest/mediaStreamPlayback.js:247:31
11:15:35     INFO -      @dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html:18:3
11:15:35     INFO -  GECKO(6344) | MEMORY STAT | vsize 17303660MB | vsizeMaxContiguous 115361197MB | residentFast 1209MB
11:15:35     INFO -  1100 INFO TEST-OK | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | took 60391ms
11:15:35     INFO -  1101 INFO TEST-PASS | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | Test must leave no active gUM streams behind.
11:15:35     INFO -  1102 ERROR [SimpleTest.finish()] this test already called finish!
11:15:35    ERROR -  1103 INFO TEST-UNEXPECTED-ERROR | dom/media/tests/mochitest/test_getUserMedia_stopVideoAudioStreamWithFollowupVideoAudio.html | called finish() multiple times

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-central: 2

Platform breakdown:
* windows10-64: 2

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

Comment 5

[Firefox Bug Husbandry Bot]

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

Comment 6

[Natalia Csoregi [:nataliaCs]]

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

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1447598&startday=2018-08-27&endday=2018-09-02&tree=all

Comment 8

[Firefox Bug Husbandry Bot]

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

Comment 9

[Narcis Beleuzu [:NarcisB]]

Fail reappeared on inbound.
Log link: https://treeherder.mozilla.org/logviewer.html#?job_id=202437515&repo=mozilla-inbound&lineNumber=9877

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* windows10-64: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows10-64: 1

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

Comment 12

[Firefox Bug Husbandry Bot]

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