1511119 - Intermittent dom/media/test/test_arraybuffer.html | Test timed out!

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

Filed by: apavel [at] mozilla.com

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

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

17:32:31     INFO -  runtests.py | Running tests: start.
17:32:32     INFO -  adb Granting important runtime permissions to org.mozilla.fennec_aurora
17:32:34     INFO -  adb launch_application: am start -W -n org.mozilla.fennec_aurora/org.mozilla.gecko.BrowserApp -a android.intent.action.VIEW --es env9 MOZ_UPLOAD_DIR=/sdcard/tests/mozlog --es env8 R_LOG_DESTINATION=stderr --es args "-no-remote -profile /sdcard/tests/profile//" --es env3 DISABLE_UNSAFE_CPOW_WARNINGS=1 --es env2 R_LOG_VERBOSE=1 --es env1 XPCOM_DEBUG_BREAK=stack --es env0 MOZ_CRASHREPORTER=1 --es env7 MOZ_LOG_FILE=/sdcard/tests/mozlog/moz.log --es env6 MOZ_CRASHREPORTER_SHUTDOWN=1 --es env5 MOZ_IN_AUTOMATION=1 --es env4 MOZ_DISABLE_NONLOCAL_CONNECTIONS=1 --es env12 MOZ_HIDE_RESULTS_TABLE=1 --es env11 R_LOG_LEVEL=6 --es env10 MOZ_CRASHREPORTER_NO_REPORT=1 -d "http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&logFile=%2Fsdcard%2Ftests%2Flogs%2Fmochitest.log&fileLevel=INFO&consoleLevel=INFO&hideResultsTable=1&manifestFile=tests.json&dumpOutputDirectory=%2Fsdcard%2Ftests"
17:32:34     INFO -  remoteautomation.py | Application pid: 6358
17:32:38     INFO -  file=[xpconnect wrapped nsIFile]
17:32:39     INFO -  file=[xpconnect wrapped nsIFile]
17:32:39     INFO -  file=[xpconnect wrapped nsIFile]
17:32:39     INFO -  file=[xpconnect wrapped nsIFile]
17:32:40     INFO -  file=[xpconnect wrapped nsIFile]
17:32:40     INFO -  file=[xpconnect wrapped nsIFile]
17:32:40     INFO -  file=[xpconnect wrapped nsIFile]
17:32:41     INFO -  file=[xpconnect wrapped nsIFile]
17:32:41     INFO -  file=[xpconnect wrapped nsIFile]
17:32:41     INFO -  file=[xpconnect wrapped nsIFile]
17:32:45     INFO -  54 INFO SimpleTest START
17:32:45     INFO -  55 INFO TEST-START | dom/media/test/test_VideoPlaybackQuality_disabled.html
17:32:45     INFO -  56 INFO TEST-OK | dom/media/test/test_VideoPlaybackQuality_disabled.html | took 1257ms
17:32:45     INFO -  57 INFO TEST-START | dom/media/test/test_access_control.html
17:32:45     INFO -  58 INFO TEST-OK | dom/media/test/test_access_control.html | took 4676ms
17:32:45     INFO -  59 INFO TEST-START | dom/media/test/test_arraybuffer.html
17:35:43     INFO -  JavaScript error: /builds/worker/workspace/build/hostutils/host-utils-61.0a1.en-US.linux-x86_64/components/httpd.js, line 2893: NS_ERROR_UNEXPECTED: Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIBinaryOutputStream.writeByteArray]
17:35:51     INFO -  <snipped 64 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
17:35:51     INFO -  Buffered messages logged at 17:32:42
17:35:51     INFO -  60 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished small-shot.flac-4 t=0.313] Length of array should match number of running tests
17:35:51     INFO -  61 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started r11025_s16_c1-short.wav-5 t=0.313] Length of array should match number of running tests
17:35:51     INFO -  62 INFO small-shot.flac: got emptied event
17:35:51     INFO -  63 INFO r11025_s16_c1-short.wav: got emptied event
17:35:51     INFO -  64 INFO r11025_s16_c1-short.wav: got loadstart event
17:35:51     INFO -  65 INFO r11025_s16_c1-short.wav: got suspend event
17:35:51     INFO -  66 INFO r11025_s16_c1-short.wav: got durationchange event
17:35:51     INFO -  67 INFO r11025_s16_c1-short.wav: got loadedmetadata event
17:35:51     INFO -  68 INFO r11025_s16_c1-short.wav: got loadeddata event
17:35:51     INFO -  69 INFO r11025_s16_c1-short.wav: got canplay event
17:35:51     INFO -  70 INFO r11025_s16_c1-short.wav: got canplaythrough event
17:35:51     INFO -  71 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | r11025_s16_c1-short.wav: got canplaythrough
17:35:51     INFO -  72 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | r11025_s16_c1-short.wav: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  73 INFO [finished r11025_s16_c1-short.wav-5] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  74 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished r11025_s16_c1-short.wav-5 t=0.358] Length of array should match number of running tests
17:35:51     INFO -  75 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started 320x240.ogv-6 t=0.359] Length of array should match number of running tests
17:35:51     INFO -  76 INFO r11025_s16_c1-short.wav: got emptied event
17:35:51     INFO -  77 INFO 320x240.ogv: got emptied event
17:35:51     INFO -  78 INFO 320x240.ogv: got loadstart event
17:35:51     INFO -  79 INFO 320x240.ogv: got suspend event
17:35:51     INFO -  80 INFO 320x240.ogv: got durationchange event
17:35:51     INFO -  81 INFO 320x240.ogv: got loadedmetadata event
17:35:51     INFO -  82 INFO 320x240.ogv: got loadeddata event
17:35:51     INFO -  83 INFO 320x240.ogv: got canplay event
17:35:51     INFO -  84 INFO 320x240.ogv: got canplaythrough event
17:35:51     INFO -  85 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | 320x240.ogv: got canplaythrough
17:35:51     INFO -  86 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | 320x240.ogv: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  87 INFO [finished 320x240.ogv-6] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  88 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished 320x240.ogv-6 t=0.436] Length of array should match number of running tests
17:35:51     INFO -  89 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started seek-short.webm-7 t=0.438] Length of array should match number of running tests
17:35:51     INFO -  90 INFO 320x240.ogv: got emptied event
17:35:51     INFO -  91 INFO seek-short.webm: got emptied event
17:35:51     INFO -  92 INFO seek-short.webm: got loadstart event
17:35:51     INFO -  93 INFO seek-short.webm: got suspend event
17:35:51     INFO -  94 INFO seek-short.webm: got durationchange event
17:35:51     INFO -  95 INFO seek-short.webm: got loadedmetadata event
17:35:51     INFO -  Buffered messages logged at 17:32:43
17:35:51     INFO -  96 INFO seek-short.webm: got loadeddata event
17:35:51     INFO -  97 INFO seek-short.webm: got canplay event
17:35:51     INFO -  98 INFO seek-short.webm: got canplaythrough event
17:35:51     INFO -  99 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | seek-short.webm: got canplaythrough
17:35:51     INFO -  100 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | seek-short.webm: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  101 INFO [finished seek-short.webm-7] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  102 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished seek-short.webm-7 t=0.755] Length of array should match number of running tests
17:35:51     INFO -  103 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started vp9-short.webm-8 t=0.756] Length of array should match number of running tests
17:35:51     INFO -  104 INFO seek-short.webm: got emptied event
17:35:51     INFO -  105 INFO vp9-short.webm: got emptied event
17:35:51     INFO -  106 INFO vp9-short.webm: got loadstart event
17:35:51     INFO -  107 INFO vp9-short.webm: got suspend event
17:35:51     INFO -  108 INFO vp9-short.webm: got durationchange event
17:35:51     INFO -  109 INFO vp9-short.webm: got loadedmetadata event
17:35:51     INFO -  Buffered messages logged at 17:32:47
17:35:51     INFO -  110 INFO vp9-short.webm: got loadeddata event
17:35:51     INFO -  111 INFO vp9-short.webm: got canplay event
17:35:51     INFO -  112 INFO vp9-short.webm: got canplaythrough event
17:35:51     INFO -  113 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | vp9-short.webm: got canplaythrough
17:35:51     INFO -  114 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | vp9-short.webm: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  115 INFO [finished vp9-short.webm-8] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  116 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished vp9-short.webm-8 t=5.169] Length of array should match number of running tests
17:35:51     INFO -  117 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started detodos-short.opus-9 t=5.17] Length of array should match number of running tests
17:35:51     INFO -  118 INFO vp9-short.webm: got emptied event
17:35:51     INFO -  119 INFO detodos-short.opus: got emptied event
17:35:51     INFO -  120 INFO detodos-short.opus: got loadstart event
17:35:51     INFO -  121 INFO detodos-short.opus: got suspend event
17:35:51     INFO -  122 INFO detodos-short.opus: got durationchange event
17:35:51     INFO -  123 INFO detodos-short.opus: got loadedmetadata event
17:35:51     INFO -  124 INFO detodos-short.opus: got loadeddata event
17:35:51     INFO -  125 INFO detodos-short.opus: got canplay event
17:35:51     INFO -  126 INFO detodos-short.opus: got canplaythrough event
17:35:51     INFO -  127 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | detodos-short.opus: got canplaythrough
17:35:51     INFO -  128 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | detodos-short.opus: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  129 INFO [finished detodos-short.opus-9] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  130 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished detodos-short.opus-9 t=5.226] Length of array should match number of running tests
17:35:51     INFO -  131 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started gizmo-short.mp4-10 t=5.227] Length of array should match number of running tests
17:35:51     INFO -  132 INFO detodos-short.opus: got emptied event
17:35:51     INFO -  133 INFO gizmo-short.mp4: got emptied event
17:35:51     INFO -  134 INFO gizmo-short.mp4: got loadstart event
17:35:51     INFO -  135 INFO gizmo-short.mp4: got suspend event
17:35:51     INFO -  136 INFO gizmo-short.mp4: got durationchange event
17:35:51     INFO -  137 INFO gizmo-short.mp4: got loadedmetadata event
17:35:51     INFO -  138 INFO gizmo-short.mp4: got loadeddata event
17:35:51     INFO -  139 INFO gizmo-short.mp4: got canplay event
17:35:51     INFO -  140 INFO gizmo-short.mp4: got canplaythrough event
17:35:51     INFO -  141 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | gizmo-short.mp4: got canplaythrough
17:35:51     INFO -  142 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | gizmo-short.mp4: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  143 INFO [finished gizmo-short.mp4-10] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  144 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished gizmo-short.mp4-10 t=5.527] Length of array should match number of running tests
17:35:51     INFO -  145 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [started flac-s24.flac-11 t=5.527] Length of array should match number of running tests
17:35:51     INFO -  146 INFO gizmo-short.mp4: got emptied event
17:35:51     INFO -  Buffered messages logged at 17:32:48
17:35:51     INFO -  147 INFO flac-s24.flac: got emptied event
17:35:51     INFO -  148 INFO flac-s24.flac: got loadstart event
17:35:51     INFO -  149 INFO flac-s24.flac: got suspend event
17:35:51     INFO -  150 INFO flac-s24.flac: got durationchange event
17:35:51     INFO -  151 INFO flac-s24.flac: got loadedmetadata event
17:35:51     INFO -  152 INFO flac-s24.flac: got loadeddata event
17:35:51     INFO -  153 INFO flac-s24.flac: got canplay event
17:35:51     INFO -  154 INFO flac-s24.flac: got canplaythrough event
17:35:51     INFO -  155 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | flac-s24.flac: got canplaythrough
17:35:51     INFO -  156 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | flac-s24.flac: readyState is HAVE_ENOUGH_DATA
17:35:51     INFO -  157 INFO [finished flac-s24.flac-11] remaining= small-shot-mp3.mp4-3
17:35:51     INFO -  158 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished flac-s24.flac-11 t=5.824] Length of array should match number of running tests
17:35:51     INFO -  159 INFO flac-s24.flac: got emptied event
17:35:51     INFO -  Buffered messages finished
17:35:51     INFO -  160 INFO TEST-UNEXPECTED-FAIL | dom/media/test/test_arraybuffer.html | Test timed out!
17:35:51     INFO -      SimpleTest.ok@SimpleTest/SimpleTest.js:275:7
17:35:51     INFO -      onTimeout@dom/media/test/manifest.js:1778:7
17:35:51     INFO -  161 INFO small-shot-mp3.mp4-3 timed out!
17:35:51     INFO -  162 INFO [finished small-shot-mp3.mp4-3] remaining=
17:35:51     INFO -  163 INFO TEST-PASS | dom/media/test/test_arraybuffer.html | [finished small-shot-mp3.mp4-3 t=180.244] Length of array should match number of running tests
17:35:51     INFO -  164 INFO small-shot-mp3.mp4: got emptied event
17:35:51     INFO -  165 INFO Finished at Thu Nov 29 2018 09:35:42 GMT-0800 (Pacific Standard Time) (1543512942.763s)
17:35:51     INFO -  166 INFO Running time: 180.387s
17:35:51     INFO -  167 INFO TEST-OK | dom/media/test/test_arraybuffer.html | took 180470ms

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* android-hw-p2-8-0-arm7-api-16: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1511119&startday=2018-11-26&endday=2018-12-02&tree=all

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* android-hw-p2-8-0-arm7-api-16: 1

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

Comment 3

[Firefox Bug Husbandry Bot]

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

Comment 4

[Cosmin Sabou [:CosminS]]

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=246366380&repo=mozilla-central&lineNumber=3215

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• android-hw-p2-8-0-arm7-api-16: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• android-hw-p2-8-0-arm7-api-16: 1

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

Comment 7

[Firefox Bug Husbandry Bot]

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

Comment 8

[Andreea Pavel [:apavel]]

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=287101075&repo=autoland&lineNumber=2220

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• macosx1014-64-shippable: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1511119&startday=2020-02-10&endday=2020-02-16&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

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=295225340&repo=mozilla-central&lineNumber=2222

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

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

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1511119&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 14

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

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

Comment 15

[Sebastian Hengst [:aryx] (needinfo me if it's about an intermittent or backout)]

New occurrence: https://treeherder.mozilla.org/logviewer.html#?job_id=304268417&repo=try

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• android-hw-p2-8-0-android-aarch64-shippable: 1

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

Comment 17

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

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

Comment 18

[Narcis Beleuzu [:NarcisB]]

New occurrence: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=314508231&repo=autoland&lineNumber=2457

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

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

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

Comment 20

[Intermittent Failures Robot]

1 failures in 4019 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=1511119&startday=2020-09-21&endday=2020-09-27&tree=all

Comment 21

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

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

Comment 22

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 5

Platform breakdown:

• linux1804-64: 4
• linux1804-64-qr: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1511119&startday=2020-11-30&endday=2020-12-06&tree=all