1703449 - Intermittent dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out.

Status: RESOLVED DUPLICATE of bug 1781570

(Core :: Audio/Video, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: nbeleuzu [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=335659885&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/akuOV6lOSbaCPJv065ZV5Q/runs/0/artifacts/public/logs/live_backing.log

---

[task 2021-04-07T04:05:32.925Z] 04:05:32     INFO - TEST-PASS | dom/media/mediasource/test/test_AVC3_mp4.html | fetchWithXHR load uri='avc3/init.mp4' status=200 
[task 2021-04-07T04:05:32.925Z] 04:05:32     INFO - Loading buffer: [0, 687)
[task 2021-04-07T04:05:32.926Z] 04:05:32     INFO - SourceBuffer buffered ranges grew from TimeRanges:  to TimeRanges: 
[task 2021-04-07T04:05:32.926Z] 04:05:32     INFO - Buffered messages finished
[task 2021-04-07T04:05:32.926Z] 04:05:32     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out. 
[task 2021-04-07T04:05:32.926Z] 04:05:32     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:05:32.926Z] 04:05:32     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:05:32.927Z] 04:05:32     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:18
[task 2021-04-07T04:05:33.786Z] 04:05:33     INFO - GECKO(2003) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2021-04-07T04:05:33.787Z] 04:05:33     INFO - GECKO(2003) | MEMORY STAT heapAllocated not supported in this build configuration.
[task 2021-04-07T04:05:33.787Z] 04:05:33     INFO - GECKO(2003) | MEMORY STAT | vsize 130550647MB | residentFast 562MB
[task 2021-04-07T04:06:02.711Z] 04:06:02     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-07T04:06:02.711Z] 04:06:02     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out. 
[task 2021-04-07T04:06:02.711Z] 04:06:02     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:06:02.712Z] 04:06:02     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:06:02.712Z] 04:06:02     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:18
[task 2021-04-07T04:06:03.711Z] 04:06:03    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2021-04-07T04:06:03.727Z] 04:06:03     INFO - TEST-UNEXPECTED-ERROR | dom/media/mediasource/test/test_AVC3_mp4.html | called finish() multiple times
[task 2021-04-07T04:06:03.727Z] 04:06:03     INFO - TEST-INFO took 361032ms
[task 2021-04-07T04:06:32.713Z] 04:06:32     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-07T04:06:32.713Z] 04:06:32     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out. 
[task 2021-04-07T04:06:32.713Z] 04:06:32     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:06:32.713Z] 04:06:32     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:06:32.714Z] 04:06:32     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:18
[task 2021-04-07T04:06:33.718Z] 04:06:33    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2021-04-07T04:06:33.734Z] 04:06:33     INFO - TEST-UNEXPECTED-ERROR | dom/media/mediasource/test/test_AVC3_mp4.html | called finish() multiple times
[task 2021-04-07T04:06:33.734Z] 04:06:33     INFO - TEST-INFO 
[task 2021-04-07T04:07:02.718Z] 04:07:02     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out. 
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:170:18
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | 4 test timeouts, giving up. 
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:178:20
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO - TEST-UNEXPECTED-FAIL | (SimpleTest/TestRunner.js) | Skipping 67 remaining tests. 
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-04-07T04:07:02.719Z] 04:07:02     INFO -     reportError@SimpleTest/TestRunner.js:147:24
[task 2021-04-07T04:07:02.720Z] 04:07:02     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:183:20
[task 2021-04-07T04:07:03.719Z] 04:07:03    ERROR - TEST-UNEXPECTED-FAIL | SimpleTest | this test already called finish!
[task 2021-04-07T04:07:03.735Z] 04:07:03     INFO - TEST-UNEXPECTED-ERROR | (SimpleTest/TestRunner.js) | called finish() multiple times
[task 2021-04-07T04:07:03.735Z] 04:07:03     INFO - TEST-INFO 
[task 2021-04-07T04:13:13.750Z] 04:13:13     INFO - Buffered messages finished
[task 2021-04-07T04:13:13.751Z] 04:13:13    ERROR - TEST-UNEXPECTED-TIMEOUT | (SimpleTest/TestRunner.js) (finished) | application timed out after 370 seconds with no output
[task 2021-04-07T04:13:13.752Z] 04:13:13    ERROR - Force-terminating active process(es).

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 2

Platform and build breakdown:

• linux1804-64-tsan: 2
  • opt: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1703449&startday=2021-04-05&endday=2021-04-11&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64-tsan: 5
  • opt: 5

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1703449&startday=2021-04-12&endday=2021-04-18&tree=all

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 4

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=339539371&repo=mozilla-central&lineNumber=1760

[task 2021-05-12T19:06:14.837Z] 19:00:32 INFO - TEST-START | dom/media/mediasession/test/test_trigger_actionhanlder.html
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - TEST-OK | dom/media/mediasession/test/test_trigger_actionhanlder.html | took 5571ms
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - TEST-START | Shutdown
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - Passed: 30
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - Failed: 0
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - Todo: 0
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - Mode: e10s
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - Slowest: 5572ms - /tests/dom/media/mediasession/test/test_trigger_actionhanlder.html
[task 2021-05-12T19:06:14.837Z] 19:00:43 INFO - SimpleTest FINISHED
[task 2021-05-12T19:06:14.837Z] 19:00:48 INFO - wait for org.mozilla.geckoview.test complete; top activity=com.bitbar.testdroid.monitor
[task 2021-05-12T19:06:14.837Z] 19:00:48 INFO - runtestsremote.py | Application ran for: 0:00:25.924485
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Stopping web server
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Server shut down.
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Web server killed.
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Stopping web socket server
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Stopping ssltunnel
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - websocket/process bridge listening on port 8191
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - Stopping websocket/process bridge
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2021-05-12T19:06:14.837Z] 19:00:50 INFO - runtests.py | Running tests: end.
[task 2021-05-12T19:06:14.837Z] 19:00:53 INFO - Buffered messages finished
[task 2021-05-12T19:06:14.837Z] 19:00:53 INFO - Running manifest: dom/media/mediasource/test/mochitest.ini
[task 2021-05-12T19:06:14.837Z] 19:00:56 INFO - PID 1578 | pk12util: PKCS12 IMPORT SUCCESSFUL
[task 2021-05-12T19:06:14.837Z] 19:00:56 INFO - MochitestServer : launching ['/builds/task_162084583858211/workspace/build/hostutils/host-utils-85.0a1.en-US.linux-x86_64/xpcshell', '-g', '/builds/task_162084583858211/workspace/build/hostutils/host-utils-85.0a1.en-US.linux-x86_64', '-f', '/builds/task_162084583858211/workspace/build/hostutils/host-utils-85.0a1.en-US.linux-x86_64/components/httpd.js', '-e', "const _PROFILE_PATH = '/tmp/tmpeee6478s.mozrunner'; const _SERVER_PORT = '8854'; const _SERVER_ADDR = '10.7.205.222'; const _TEST_PREFIX = undefined; const _DISPLAY_RESULTS = false;", '-f', '/builds/task_162084583858211/workspace/build/tests/mochitest/server.js']
[task 2021-05-12T19:06:14.838Z] 19:00:56 INFO - runtests.py | Server pid: 1587
[task 2021-05-12T19:06:14.838Z] 19:00:56 INFO - runtests.py | Websocket server pid: 1590
[task 2021-05-12T19:06:14.838Z] 19:00:56 INFO - runtests.py | websocket/process bridge pid: 1594
[task 2021-05-12T19:06:14.838Z] 19:00:56 INFO - runtests.py | SSL tunnel pid: 1610
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with scheme: http
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with e10s: True
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with fission: False
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with cross-origin iframes: False
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with serviceworker_e10s: True
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running with socketprocess_e10s: True
[task 2021-05-12T19:06:14.838Z] 19:00:58 INFO - runtests.py | Running tests: start.
[task 2021-05-12T19:06:14.838Z] 19:00:59 INFO - adb Granting important runtime permissions to org.mozilla.geckoview.test
[task 2021-05-12T19:06:14.838Z] 19:01:01 INFO - adb launch_application: am start -W -n org.mozilla.geckoview.test/org.mozilla.geckoview.test.TestRunnerActivity -a android.intent.action.MAIN --es env0 MOZ_CRASHREPORTER_NO_REPORT=1 --es env1 MOZ_CRASHREPORTER=1 --es env2 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env3 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env4 MOZ_IN_AUTOMATION=1 --es env5 R_LOG_LEVEL=6 --es env6 R_LOG_DESTINATION=stderr --es env7 R_LOG_VERBOSE=1 --es env8 XPCOM_DEBUG_BREAK=stack --es env9 MOZ_WEBRENDER=1 --es env10 MOZ_ACCELERATED=1 --es env11 MOZ_UPLOAD_DIR=/data/local/tmp/test_root/mozlog --es env12 MOZ_HIDE_RESULTS_TABLE=1 --es arg0 -no-remote --es arg1 -profile --es arg2 /data/local/tmp/test_root/profile/ --ez use_multiprocess True -d 'http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&logFile=%2Fdata%2Flocal%2Ftmp%2Ftest_root%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fdata%2Flocal%2Ftmp%2Ftest_root'
[task 2021-05-12T19:06:14.838Z] 19:01:02 INFO - runtestsremote.py | Application pid: 8485
[task 2021-05-12T19:06:14.838Z] 19:01:09 INFO - SimpleTest START
[task 2021-05-12T19:06:14.838Z] 19:01:09 INFO - TEST-START | dom/media/mediasource/test/test_AVC3_mp4.html
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - Buffered messages logged at 19:01:07
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - TEST-PASS | dom/media/mediasource/test/test_AVC3_mp4.html | Receive a sourceopen event
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - Buffered messages logged at 19:01:08
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - TEST-PASS | dom/media/mediasource/test/test_AVC3_mp4.html | fetchWithXHR load uri='avc3/init.mp4' status=200
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - Loading buffer: [0, 687)
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - SourceBuffer buffered ranges grew from TimeRanges: to TimeRanges:
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - TEST-PASS | dom/media/mediasource/test/test_AVC3_mp4.html | fetchWithXHR load uri='avc3/segment1.m4s' status=200
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - Loading buffer: [0, 696869)
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - SourceBuffer buffered ranges grew from TimeRanges: to TimeRanges: [0, 3.84)
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - Buffered messages finished
[task 2021-05-12T19:06:14.838Z] 19:06:14 WARNING - TEST-UNEXPECTED-FAIL | dom/media/mediasource/test/test_AVC3_mp4.html | Test timed out. -
[task 2021-05-12T19:06:14.838Z] 19:06:14 INFO - TEST-OK | dom/media/mediasource/test/test_AVC3_mp4.html | took 301242ms

Comment 5

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 6

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=370395109&repo=mozilla-central

Comment 7

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform and build breakdown:

• android-hw-p2-8-0-android-aarch64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1703449&startday=2022-03-07&endday=2022-03-13&tree=all

Comment 8

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 9

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=381087750&repo=autoland

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-hw-p2-8-0-android-aarch64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1703449&startday=2022-06-13&endday=2022-06-19&tree=all

Comment 11

[BugBot [:suhaib / :marco/ :calixte]]

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit auto_nag documentation.

Comment 12

[Treeherder Bug Filer]

New failure instance: https://treeherder.mozilla.org/logviewer?job_id=384505657&repo=autoland

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform and build breakdown:

• android-hw-p2-8-0-android-aarch64-qr: 1
  • debug: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1703449&startday=2022-07-11&endday=2022-07-17&tree=all