1659064 - Intermittent dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | Test timed out -

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

Filed by: malexandru [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=312982424&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RUmtR3O3QtSj5rGEKU03lw/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/tip/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/RUmtR3O3QtSj5rGEKU03lw/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2020-08-14T02:30:15.863Z] 02:30:15     INFO - TEST-PASS | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | controller is still inactive - 
[task 2020-08-14T02:30:15.864Z] 02:30:15     INFO - remove tab
[task 2020-08-14T02:30:15.864Z] 02:30:15     INFO - Leaving test bound testNoSrcOrErrorMediaEntersPIPMode
[task 2020-08-14T02:30:15.872Z] 02:30:15     INFO - Entering test bound testNoSrcOrErrorMediaEntersFullscreen
[task 2020-08-14T02:30:15.873Z] 02:30:15     INFO - open media page https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html
[task 2020-08-14T02:30:15.873Z] 02:30:15     INFO - controller should always inactive
[task 2020-08-14T02:30:15.873Z] 02:30:15     INFO - enter fullscreen several times and controller should keep inactive
[task 2020-08-14T02:30:15.874Z] 02:30:15     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_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.874Z] 02:30:15     INFO - Buffered messages logged at 02:28:50
[task 2020-08-14T02:30:15.874Z] 02:30:15     INFO - Console message: [JavaScript Error: "Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized" {file: "resource:///actors/DOMFullscreenParent.jsm" line: 113}]
[task 2020-08-14T02:30:15.874Z] 02:30:15     INFO - handleEvent@resource:///actors/DOMFullscreenParent.jsm:113:28
[task 2020-08-14T02:30:15.874Z] 02:30:15     INFO - 
[task 2020-08-14T02:30:15.875Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "resource:///actors/DOMFullscreenChild.jsm" line: 30}]
[task 2020-08-14T02:30:15.875Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.875Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.876Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.885Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.885Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.885Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.886Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.886Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.886Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.887Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.888Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.888Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.888Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.888Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.889Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.889Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.890Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.894Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.895Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.895Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.899Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.899Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.899Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.899Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html" line: 0}]
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - Buffered messages finished
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | Test timed out - 
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - GECKO(10222) | MEMORY STAT | vsize 3102MB | residentFast 333MB | heapAllocated 74MB
[task 2020-08-14T02:30:15.900Z] 02:30:15     INFO - TEST-OK | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | took 90026ms
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | Found a tab after previous test timed out: https://example.com/browser/dom/media/mediacontrol/tests/file_no_src_media.html - 
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - GECKO(10222) | JavaScript error: , line 0: TypeError: Fullscreen request aborted
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - GECKO(10222) | [Child 10428: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7fcb35a50000 == 1 [pid = 10428] [id = {fdd135e1-e47e-4f55-a9e6-fa99a4828e94}]
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - GECKO(10222) | [Child 10428: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7fcb54293180) [pid = 10428] [serial = 52] [outer = (nil)]
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - GECKO(10222) | [Child 10428: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7fcb35a51000) [pid = 10428] [serial = 53] [outer = 0x7fcb54293180]
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - GECKO(10222) | [Child 10428: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 3 (0x7fcb35a56c00) [pid = 10428] [serial = 54] [outer = 0x7fcb54293180]
[task 2020-08-14T02:30:15.903Z] 02:30:15     INFO - checking window state
[task 2020-08-14T02:30:16.000Z] 02:30:15     INFO - TEST-START | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js
[task 2020-08-14T02:30:16.036Z] 02:30:16     INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-08-14T02:30:16.036Z] 02:30:16     INFO - Buffered messages logged at 02:30:15
[task 2020-08-14T02:30:16.037Z] 02:30:16     INFO - Entering test bound setupTestingPref
[task 2020-08-14T02:30:16.037Z] 02:30:16     INFO - Buffered messages finished
[task 2020-08-14T02:30:16.037Z] 02:30:16     INFO - TEST-UNEXPECTED-FAIL | dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js | Uncaught exception received from previously timed out test - AbortError: Actor 'SpecialPowers' destroyed before query 'Spawn' was resolved

Comment 1

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 4
• mozilla-central: 2

Platform breakdown:

• windows7-32: 4
• linux1804-64: 1
• windows7-32-shippable: 1

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

Comment 2

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

Set release status flags based on info from the regressing bug 1656642

Comment 3

[Alastor Wu [:alwu]]

Attachment: Bug 1659064 - resolve promise after element leaves fullscreen.

Comment 4

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/7a27f7be2912
resolve promise after element leaves fullscreen. r=bryce

Comment 5

[Atila Butkovits]

https://hg.mozilla.org/mozilla-central/rev/7a27f7be2912

Comment 6

[Razvan Maries]

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

Alastor, could you take a look?

Comment 7

[Alastor Wu [:alwu]]

Attachment: Bug 1659064 - wait until tab becomes a focus tab.

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/89a87a32b6c2
wait until tab becomes a focus tab. r=bryce

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 3
• autoland: 13

Platform breakdown:

• windows7-32: 11
• windows7-32-shippable: 2
• linux1804-64: 3

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

Comment 10

[Narcis Beleuzu [:NarcisB]]

https://hg.mozilla.org/mozilla-central/rev/89a87a32b6c2

Comment 11

[Narcis Beleuzu [:NarcisB]]

:alwu , it`s still failing
Log link: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=313826094&repo=autoland&lineNumber=16437

Comment 12

[Alastor Wu [:alwu]]

Hi, Smaug,

Do you know if there is any way to prevent from getting error Request for fullscreen was denied because requesting element is not in the currently focused tab when calling requestFullscreen()?
I've done a check [1] to ensure the document is visible (so it should be in a focus tab), but it seems not enough.

Thank you.

[1] https://searchfox.org/mozilla-central/rev/73a14f1b367948faa571ed2fe5d7eb29460787c1/dom/media/mediacontrol/tests/browser_nosrc_and_error_media.js#79-95

Comment 13

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 14

[Olli Pettay [:smaug][[e-mail]]]

edgar has been looking into some fullscreen issues recently. He might be good suggestions for this bug.

Comment 15

[Alastor Wu [:alwu]]

The last failure happened on 8/24, which was 3 weeks ago. It seems to me the failure has been fixed and we can close this bug.

Comment 17

[Razvan Maries]

New occurrence: https://treeherder.mozilla.org/logviewer?job_id=320656201&repo=autoland&lineNumber=63912

Comment 18

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 1

Platform breakdown:

• linux1804-64: 1

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

Comment 19

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 1
• mozilla-central: 2
• autoland: 1

Platform breakdown:

• linux1804-64: 2
• linux1804-64-shippable: 2

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1659064&startday=2020-11-09&endday=2020-11-15&tree=all

Comment 20

[Intermittent Failures Robot]

48 failures in 873 pushes (0.055 failures/push) were associated with this bug yesterday.

Repository breakdown:

• autoland: 30
• mozilla-central: 13
• try: 4
• mozilla-beta: 1

Platform breakdown:

• linux1804-64-asan: 14
• linux1804-64: 25
• linux1804-64-ccov: 2
• linux1804-64-shippable: 5
• linux1804-64-devedition: 2

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

Comment 21

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

Recent failures are a regression from bug 1673509.

Comment 22

[Alastor Wu [:alwu]]

Attachment: Bug 1659064 - ensure the focus would be gaved back to the original window.

In D97474, I removed this line because I think we acutally didn't need to wait until the focus switched to the original window. However, the intermittent fail rate went high after doing that.

Therefore, adding this line back in order to not interfere the following fullscreen test, which would get failed if the window is not being focused.

Comment 23

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/ee1c2bebabbe
ensure the focus would be gaved back to the original window. r=bryce

Comment 24

[Intermittent Failures Robot]

52 failures in 1013 pushes (0.051 failures/push) were associated with this bug yesterday.

Repository breakdown:

• try: 13
• autoland: 23
• mozilla-central: 16

Platform breakdown:

• linux1804-64: 30
• linux1804-64-asan: 12
• linux1804-64-ccov: 3
• linux1804-64-shippable: 5
• linux1804-64-devedition: 2

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

Comment 25

[Andreea Pavel [:apavel]]

https://hg.mozilla.org/mozilla-central/rev/ee1c2bebabbe

Comment 26

[Intermittent Failures Robot]

109 failures in 4810 pushes (0.023 failures/push) were associated with this bug in the last 7 days.

This is the #3 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: 58
• try: 19
• mozilla-beta: 3
• mozilla-central: 29

Platform breakdown:

• linux1804-64: 61
• linux1804-64-shippable: 12
• linux1804-64-asan: 27
• linux1804-64-ccov: 5
• linux1804-64-devedition: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1659064&startday=2020-11-16&endday=2020-11-22&tree=all