1625099 - High frequency dom/media/mediacontrol/tests/browser_media_control_metadata.js | Test timed out -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

Filed by: rmaries [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=294822514&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/CjtEkmmHTcq35xJpAK8n1w/runs/0/artifacts/public/logs/live_backing.log

---

[task 2020-03-26T07:48:51.803Z] 07:48:51 INFO - TEST-START | dom/media/mediacontrol/tests/browser_media_control_metadata.js
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_media_control_metadata.js | artwork sizes '128x128' is equal to 128x128 -
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_media_control_metadata.js | artwork type 'image/jpeg' is equal to image/jpeg -
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - open another page in tab2
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - Console message: [JavaScript Error: "The character encoding of the HTML document was not declared. The document will render with garbled text in some browser configurations if the document contains characters from outside the US-ASCII range. The character encoding of the page must be declared in the document or in the transfer protocol." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_non_autoplay.html" line: 0}]
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - set metadata for tab2
[task 2020-03-26T07:50:21.925Z] 07:50:21 INFO - start media in tab2
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - Buffered messages finished
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_media_control_metadata.js | Test timed out -
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - GECKO(3348) | MEMORY STAT | vsize 2104270MB | vsizeMaxContiguous 65074431MB | residentFast 289MB | heapAllocated 87MB
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - TEST-OK | dom/media/mediacontrol/tests/browser_media_control_metadata.js | took 90076ms
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_media_control_metadata.js | Found a tab after previous test timed out: https://example.com/browser/dom/media/mediacontrol/tests/file_non_autoplay.html -
[task 2020-03-26T07:50:21.926Z] 07:50:21 INFO - GECKO(3348) | [Parent 7380, Main Thread] WARNING: '!mName', file /builds/worker/checkouts/gecko/editor/libeditor/EditAggregateTransaction.cpp, line 91
[task 2020-03-26T07:50:21.927Z] 07:50:21 INFO - GECKO(3348) | [Parent 7380, Main Thread] WARNING: EditAggregationTransaction::GetName() failed: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 217
[task 2020-03-26T07:50:21.927Z] 07:50:21 INFO - GECKO(3348) | [Parent 7380, Main Thread] WARNING: nsIAbsorbingTransaction::GetTxnName() failed, but ignored: 'NS_SUCCEEDED(rvIgnored)', file /builds/worker/checkouts/gecko/editor/libeditor/PlaceholderTransaction.cpp, line 188
[task 2020-03-26T07:50:21.929Z] 07:50:21 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-03-26T07:50:21.929Z] 07:50:21 INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_media_control_metadata.js | Found a tab after previous test timed out: https://example.com/browser/dom/media/mediacontrol/tests/file_non_autoplay.html -
[task 2020-03-26T07:50:21.967Z] 07:50:21 INFO - GECKO(3348) | [Child 7424: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 000002265B94C800 == 1 [pid = 7424] [id = {b3da3351-03cd-4fc7-84ca-972c41e45a92}]
[task 2020-03-26T07:50:21.967Z] 07:50:21 INFO - GECKO(3348) | [Child 7424: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0000022662072090) [pid = 7424] [serial = 13] [outer = 0000000000000000]
[task 2020-03-26T07:50:21.967Z] 07:50:21 INFO - GECKO(3348) | [Child 7424: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0000022661C99000) [pid = 7424] [serial = 14] [outer = 0000022662072090]
[task 2020-03-26T07:50:21.996Z] 07:50:21 INFO - GECKO(3348) | [Child 7424: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (00000226620A2000) [pid = 7424] [serial = 15] [outer = 0000022662072090]
[task 2020-03-26T07:50:21.996Z] 07:50:21 INFO - checking window state
[task 2020-03-26T07:50:22.026Z] 07:50:22 INFO - GECKO(3348) | JavaScript error: resource://gre/actors/AudioPlaybackParent.jsm, line 19: TypeError: can't access property "audioPlaybackStopped", browser is null

Comment 1

[Intermittent Failures Robot]

1 failures in 4773 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=1625099&startday=2020-03-23&endday=2020-03-29&tree=all

Comment 2

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

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-asan: 1

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

Comment 4

[Alexandru Michis [:malexandru]]

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

Comment 5

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1
• mozilla-central: 1

Platform breakdown:

• windows10-64-qr: 1
• windows10-64: 1

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

Comment 6

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-release: 1

Platform breakdown:

• windows10-64: 1

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

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows10-64: 1

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

Comment 9

[Cristian Brindusan [:cbrindusan]]

New occurrence:
https://treeherder.mozilla.org/logviewer?job_id=323604937&repo=mozilla-central&lineNumber=40809

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1

Platform breakdown:

• windows10-64-shippable-qr: 1

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

Comment 11

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1

Platform breakdown:

• linux1804-64-tsan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2020-12-14&endday=2020-12-20&tree=all

Comment 12

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-esr78: 1

Platform breakdown:

• windows10-64: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2020-12-21&endday=2020-12-27&tree=all

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64-tsan: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2020-12-28&endday=2021-01-03&tree=all

Comment 14

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-beta: 1
• mozilla-central: 1

Platform breakdown:

• linux1804-64-tsan: 2

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

Comment 15

[Intermittent Failures Robot]

39 failures in 720 pushes (0.054 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 33
• mozilla-central: 6

Platform and build breakdown:

• windows7-32: 36
  • debug: 10
  • opt: 26
• windows7-32-shippable: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2021-01-21&endday=2021-01-21&tree=all

Comment 16

[Andreea Pavel [:apavel]]

Retriggers: https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&test_paths=dom%2Fmedia%2Fmediacontrol%2Ftests%2Fbrowser&searchStr=win%2C7%2Copt&tochange=7412605f5c473f386955a04f84d1e52666ff5f7d&fromchange=0c0b1e3fab140328571088a912eb77c046fb7217&selectedTaskRun=F8udX6N-ScSk0KXweTRlew.0

Aryx can you take a look?

Comment 17

[Intermittent Failures Robot]

15 failures in 665 pushes (0.023 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 9
• mozilla-central: 4
• try: 2

Platform and build breakdown:

• windows7-32: 12
  • debug: 7
  • opt: 5
• windows7-32-shippable: 3
  • opt: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2021-01-22&endday=2021-01-22&tree=all

Comment 18

[Natalia Csoregi [:nataliaCs]]

Possibly started with Bug 1604247

Comment 19

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

Likely from bug 1685399.

Comment 20

[Noemi Erli[:noemi_erli]]

We had ~74 failures in the past week, all on win debug and opt: https://treeherder.mozilla.org/intermittent-failures/bugdetails?startday=2021-01-16&endday=2021-01-23&tree=all&bug=1625099
Most recent one: https://treeherder.mozilla.org/logviewer?job_id=327539283&repo=autoland&lineNumber=6274

Alastor, could you please take a look at this?

Comment 21

[Alastor Wu [:alwu]]

Attachment: Bug 1625099 - no need to wait for `metadatachange`.

When controller gets deactivated, we would have had reset the display metadata already, so we can remove the promise for metadatachange.

The reason of causing failure is because metadatachange sometime would race with deactivated. If metadatachange gets dispatched after deactivated, we would miss that event.

The actual fix for event race will be handled in bug 1688507, this patch is simply fixing the failure.

Comment 22

[Intermittent Failures Robot]

90 failures in 3488 pushes (0.026 failures/push) were associated with this bug in the last 7 days.

This is the #10 most frequent failure this week.

** This failure happened more than 75 times this week! Resolving this bug is a very high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 1 week, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 70
• mozilla-central: 15
• try: 5

Platform and build breakdown:

• windows7-32: 79
  • opt: 52
  • debug: 27
• windows7-32-shippable: 11
  • opt: 11

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

Comment 23

[Alastor Wu [:alwu]]

I don't think they're related.

Comment 24

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/39b116dd7e27
no need to wait for `metadatachange`. r=bryce

Comment 25

[Sandor Molnar[:smolnar]]

https://hg.mozilla.org/mozilla-central/rev/39b116dd7e27

Comment 26

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

https://hg.mozilla.org/releases/mozilla-beta/rev/294d60f564a3

Comment 27

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• try: 2
• mozilla-central: 3
• mozilla-beta: 3

Platform and build breakdown:

• windows7-32: 8
  • debug: 4
  • opt: 4
• windows7-32-shippable: 6
  • opt: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1625099&startday=2021-01-25&endday=2021-01-31&tree=all