1694075 - Intermittent dom/media/test/test_bug495300.html | Test timed out!

Status: RESOLVED INCOMPLETE

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

Description

[Treeherder Bug Filer]

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

---

[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  Buffered messages logged at 04:32:28
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | A valid string reason is expected
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Reason cannot be empty
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  Started Mon Feb 22 2021 04:32:28 GMT+0000 (Coordinated Universal Time) (1613968348.277s)
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started r11025_s16_c1.wav-0 t=0.024] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started audio.wav-1 t=0.029] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: audio.wav
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: audio.wav
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  [finished audio.wav-1] remaining= r11025_s16_c1.wav-0
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished audio.wav-1 t=0.112] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started seek.ogv-2 t=0.116] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: r11025_s16_c1.wav
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: r11025_s16_c1.wav
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  [finished r11025_s16_c1.wav-0] remaining= seek.ogv-2
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished r11025_s16_c1.wav-0 t=0.126] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started 320x240.ogv-3 t=0.13] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: 320x240.ogv
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: 320x240.ogv
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  [finished 320x240.ogv-3] remaining= seek.ogv-2
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished 320x240.ogv-3 t=0.274] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started seek.webm-4 t=0.279] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: seek.ogv
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: seek.ogv
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  [finished seek.ogv-2] remaining= seek.webm-4
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished seek.ogv-2 t=0.326] Length of array should match number of running tests
[task 2021-02-22T04:35:39.266Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started sine.webm-5 t=0.328] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: seek.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: seek.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished seek.webm-4] remaining= sine.webm-5
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished seek.webm-4 t=0.488] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started bug516323.indexed.ogv-6 t=0.49] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: sine.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: sine.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished sine.webm-5] remaining= bug516323.indexed.ogv-6
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished sine.webm-5 t=0.506] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started split.webm-7 t=0.508] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: bug516323.indexed.ogv
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: bug516323.indexed.ogv
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished bug516323.indexed.ogv-6] remaining= split.webm-7
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished bug516323.indexed.ogv-6 t=0.675] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started detodos.opus-8 t=0.68] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: split.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: split.webm
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished split.webm-7] remaining= detodos.opus-8
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished split.webm-7 t=0.719] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started gizmo.mp4-9 t=0.721] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  Buffered messages logged at 04:32:29
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: detodos.opus
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: detodos.opus
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished detodos.opus-8] remaining= gizmo.mp4-9
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished detodos.opus-8 t=0.74] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started owl.mp3-10 t=0.743] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: owl.mp3
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: owl.mp3
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished owl.mp3-10] remaining= gizmo.mp4-9
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished owl.mp3-10 t=0.969] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [started bug482461-theora.ogv-12 t=0.971] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | Got expected 'ended' event: bug482461-theora.ogv
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | currentTime equals duration: bug482461-theora.ogv
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished bug482461-theora.ogv-12] remaining= gizmo.mp4-9
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished bug482461-theora.ogv-12 t=1.22] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  Buffered messages finished
[task 2021-02-22T04:35:39.267Z] 04:35:39  WARNING -  TEST-UNEXPECTED-FAIL | dom/media/test/test_bug495300.html | Test timed out!
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -      SimpleTest.ok@SimpleTest/SimpleTest.js:417:16
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -      onTimeout@dom/media/test/manifest.js:2109:9
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  gizmo.mp4-9 timed out!
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  [finished gizmo.mp4-9] remaining=
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-PASS | dom/media/test/test_bug495300.html | [finished gizmo.mp4-9 t=180.741] Length of array should match number of running tests
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  Finished at Mon Feb 22 2021 04:35:29 GMT+0000 (Coordinated Universal Time) (1613968529.166s)
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  Running time: 180.896s
[task 2021-02-22T04:35:39.267Z] 04:35:39     INFO -  TEST-OK | dom/media/test/test_bug495300.html | took 181103ms```

Comment 1

[Intermittent Failures Robot]

1 failures in 4327 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=1694075&startday=2021-02-22&endday=2021-02-28&tree=all

Comment 2

[Intermittent Failures Robot]

1 failures in 4622 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=1694075&startday=2021-03-08&endday=2021-03-14&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

[Sandor Molnar[:smolnar]]

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

Comment 5

[Intermittent Failures Robot]

1 failures in 3061 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=1694075&startday=2021-05-17&endday=2021-05-23&tree=all

Comment 6

[Intermittent Failures Robot]

1 failures in 3502 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
  • opt: 1

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

Comment 7

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

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

Comment 8

[Treeherder Bug Filer]

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

Comment 9

[Intermittent Failures Robot]

1 failures in 3281 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=1694075&startday=2022-02-14&endday=2022-02-20&tree=all

Comment 10

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

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