Closed Bug 1655139 Opened 4 years ago Closed 2 years ago

Intermittent dom/html/test/test_fullscreen-api.html | Test timed out.

Categories

(Core :: DOM: Core & HTML, defect)

defect
Not set
normal

Tracking

()

RESOLVED DUPLICATE of bug 1776996

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell unknown])

Filed by: archaeopteryx [at] coole-files.de
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=310946864&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/ZD9njEq_QLSBUNAEhPj-5A/runs/0/artifacts/public/logs/live_backing.log


[task 2020-07-24T12:24:53.337Z] 12:24:53 INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Full-screen element in inner frame should be null.
[task 2020-07-24T12:24:53.338Z] 12:24:53 INFO - Buffered messages finished
[task 2020-07-24T12:24:53.338Z] 12:24:53 INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out.
[task 2020-07-24T12:24:53.338Z] 12:24:53 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:412:16
[task 2020-07-24T12:24:53.338Z] 12:24:53 INFO - reportError@SimpleTest/TestRunner.js:138:22
[task 2020-07-24T12:24:53.338Z] 12:24:53 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:160:18

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE

There have been 32 total failures in the last 7 days, recent failure log.
Affected platforms are:

  • windows10-64-2004-shippable-qr
  • windows10-64-2004-qr
  • windows10-32-2004-shippable-qr
  • windows10-32-2004-qr
  • macosx1015-64-shippable-qr
  • macosx1015-64-qr
  • linux1804-64-shippable-qr
  • linux1804-64-qr
[task 2022-03-18T06:05:13.807Z] 06:05:13     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Full-screen element should be null after removing. 
[task 2022-03-18T06:05:13.808Z] 06:05:13     INFO - Buffered messages finished
[task 2022-03-18T06:05:13.808Z] 06:05:13     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out. - 
[task 2022-03-18T06:05:14.552Z] 06:05:14     INFO - GECKO(4300) | MEMORY STAT | vsize 2713MB | residentFast 144MB | heapAllocated 11MB
[task 2022-03-18T06:05:14.558Z] 06:05:14     INFO - TEST-OK | dom/html/test/test_fullscreen-api.html | took 316089ms
Flags: needinfo?(echen)

The recent intermittent spike happens on file_fullscreen-resize.html and headless mode, I think it is because of bug 1766537.

Depends on: 1766537
Flags: needinfo?(echen)
Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell needswork:owner]

There are 56 total failures in the last 7 days on

[task 2022-05-02T22:24:46.308Z] 22:24:46     INFO - TEST-START | dom/html/test/test_fullscreen-api.html
[task 2022-05-02T22:24:46.739Z] 22:24:46     INFO - GECKO(5776) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 185: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-02T22:24:46.762Z] 22:24:46     INFO - GECKO(5776) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 155: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2022-05-02T22:24:46.986Z] 22:24:46     INFO - GECKO(5776) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 191: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-02T22:24:47.239Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: , line 0: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:47.340Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 185: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-02T22:24:47.362Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 155: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2022-05-02T22:24:47.825Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:47.847Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:47.859Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:47.889Z] 22:24:47     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 101: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:53.910Z] 22:24:53     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 115: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:53.919Z] 22:24:53     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:53.931Z] 22:24:53     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:53.965Z] 22:24:53     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:53.986Z] 22:24:53     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:54.017Z] 22:24:54     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:54.032Z] 22:24:54     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:54.049Z] 22:24:54     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:54.080Z] 22:24:54     INFO - GECKO(5776) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-02T22:24:54.313Z] 22:24:54     INFO - GECKO(5776) | JavaScript error: , line 0: TypeError: Fullscreen request denied

[task 2022-05-02T22:29:50.804Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2022-05-02T22:29:50.804Z] 22:29:50     INFO - Waiting for focus to return to main window
[task 2022-05-02T22:29:50.804Z] 22:29:50     INFO - main window focused, starting next test
[task 2022-05-02T22:29:50.805Z] 22:29:50     INFO - Run test file_fullscreen-lenient-setters.html
[task 2022-05-02T22:29:50.805Z] 22:29:50     INFO - must wait for focus
[task 2022-05-02T22:29:50.805Z] 22:29:50     INFO - Were focused
[task 2022-05-02T22:29:50.806Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreen should not be changed 
[task 2022-05-02T22:29:50.806Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreenElement should not be changed 
[task 2022-05-02T22:29:50.807Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreenEnabled should not be changed 
[task 2022-05-02T22:29:50.807Z] 22:29:50     INFO - Waiting for focus to return to main window
[task 2022-05-02T22:29:50.807Z] 22:29:50     INFO - main window focused, starting next test
[task 2022-05-02T22:29:50.808Z] 22:29:50     INFO - Run test file_fullscreen-table.html
[task 2022-05-02T22:29:50.808Z] 22:29:50     INFO - Were focused
[task 2022-05-02T22:29:50.808Z] 22:29:50     INFO - [table] The default background of window should be white
[task 2022-05-02T22:29:50.809Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [table] reftest comparison: == snapshot white 
[task 2022-05-02T22:29:50.809Z] 22:29:50     INFO - Buffered messages logged at 22:25:00
[task 2022-05-02T22:29:50.809Z] 22:29:50     INFO - [table] The table with green background should be in fullscreen
[task 2022-05-02T22:29:50.809Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [table] reftest comparison: == snapshot green 
[task 2022-05-02T22:29:50.810Z] 22:29:50     INFO - [table] When the table becames transparent, the black backdrop should appear
[task 2022-05-02T22:29:50.810Z] 22:29:50     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [table] reftest comparison: == snapshot black 
[task 2022-05-02T22:29:50.810Z] 22:29:50     INFO - Buffered messages finished
[task 2022-05-02T22:29:50.811Z] 22:29:50     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out. - 
[task 2022-05-02T22:29:51.688Z] 22:29:51     INFO - GECKO(5776) | MEMORY STAT | vsize 2103678MB | vsizeMaxContiguous 68318270MB | residentFast 54MB | heapAllocated 5MB
[task 2022-05-02T22:29:51.701Z] 22:29:51     INFO - TEST-OK | dom/html/test/test_fullscreen-api.html | took 305394ms
[task 2022-05-02T22:29:54.710Z] 22:29:54     INFO - Error: Unable to restore focus, expect failures and timeouts.

Edgar, can the patch in bug 1766537 land?

Flags: needinfo?(echen)

(In reply to Andreea Pavel [:apavel] from comment #72)

Edgar, can the patch in bug 1766537 land?

Yes, the patch is ready to land. I am going to land it.

Flags: needinfo?(echen)
Flags: needinfo?(echen)

There are 29 total failures in the last 7 days on

  • windows10-64-2004-shippable-qr opt
  • windows10-32-2004-qr opt

Recent failure log: https://treeherder.mozilla.org/logviewer?job_id=379120297&repo=autoland&lineNumber=7043

[task 2022-05-24T13:17:54.643Z] 13:17:54     INFO - TEST-START | dom/html/test/test_fullscreen-api.html
[task 2022-05-24T13:17:54.804Z] 13:17:54     INFO - GECKO(1904) | 1653398274816	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:54.952Z] 13:17:54     INFO - GECKO(1904) | 1653398274965	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:55.005Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 202: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-24T13:17:55.009Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 172: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2022-05-24T13:17:55.136Z] 13:17:55     INFO - GECKO(1904) | 1653398275140	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:55.252Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 208: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-24T13:17:55.406Z] 13:17:55     INFO - GECKO(1904) | 1653398275419	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:55.495Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: , line 0: TypeError: Fullscreen request denied
[task 2022-05-24T13:17:55.622Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 202: Error: TelemetryStopwatch: key "FULLSCREEN_CHANGE_MS" was already initialized
[task 2022-05-24T13:17:55.648Z] 13:17:55     INFO - GECKO(1904) | JavaScript error: resource:///actors/DOMFullscreenParent.jsm, line 172: Error: TelemetryStopwatch: finishing nonexisting stopwatch. Histogram: "FULLSCREEN_CHANGE_MS", key: ""
[task 2022-05-24T13:17:55.838Z] 13:17:55     INFO - GECKO(1904) | 1653398275848	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:56.085Z] 13:17:56     INFO - GECKO(1904) | 1653398276097	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:17:56.126Z] 13:17:56     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-24T13:17:56.167Z] 13:17:56     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-24T13:17:56.207Z] 13:17:56     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied-inner.html, line 20: TypeError: Fullscreen request denied
[task 2022-05-24T13:17:56.226Z] 13:17:56     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 101: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.261Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 115: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.268Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.296Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.328Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.358Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.395Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.413Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.443Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.460Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: http://mochi.test:8888/tests/dom/html/test/file_fullscreen-denied.html, line 131: TypeError: Fullscreen request denied
[task 2022-05-24T13:18:02.611Z] 13:18:02     INFO - GECKO(1904) | 1653398282622	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-05-24T13:18:02.652Z] 13:18:02     INFO - GECKO(1904) | JavaScript error: , line 0: TypeError: Fullscreen request denied

[task 2022-05-24T13:23:02.533Z] 13:23:02     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [async] Entered fullscreen 
[task 2022-05-24T13:23:02.534Z] 13:23:02     INFO - Waiting for focus to return to main window
[task 2022-05-24T13:23:02.534Z] 13:23:02     INFO - main window focused, starting next test
[task 2022-05-24T13:23:02.534Z] 13:23:02     INFO - Run test file_fullscreen-sub-iframe.html
[task 2022-05-24T13:23:02.535Z] 13:23:02     INFO - Were focused
[task 2022-05-24T13:23:02.535Z] 13:23:02     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [sub-iframe] Entered fullscreen 
[task 2022-05-24T13:23:02.535Z] 13:23:02     INFO - Waiting for focus to return to main window
[task 2022-05-24T13:23:02.536Z] 13:23:02     INFO - main window focused, starting next test
[task 2022-05-24T13:23:02.536Z] 13:23:02     INFO - Run test file_fullscreen-with-full-zoom.html
[task 2022-05-24T13:23:02.536Z] 13:23:02     INFO - Were focused
[task 2022-05-24T13:23:02.536Z] 13:23:02     INFO - Setting full zoom to 30%
[task 2022-05-24T13:23:02.537Z] 13:23:02     INFO - The element with green background should be in fullscreen
[task 2022-05-24T13:23:02.537Z] 13:23:02     INFO - Buffered messages logged at 13:18:11
[task 2022-05-24T13:23:02.537Z] 13:23:02     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | reftest comparison: == snapshot green 
[task 2022-05-24T13:23:02.538Z] 13:23:02     INFO - Buffered messages finished
[task 2022-05-24T13:23:02.538Z] 13:23:02     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out. - 
[task 2022-05-24T13:23:03.384Z] 13:23:03     INFO - GECKO(1904) | MEMORY STAT | vsize 582MB | vsizeMaxContiguous 1293MB | residentFast 88MB | heapAllocated 9MB
[task 2022-05-24T13:23:03.399Z] 13:23:03     INFO - TEST-OK | dom/html/test/test_fullscreen-api.html | took 308756ms
[task 2022-05-24T13:23:06.408Z] 13:23:06     INFO - Error: Unable to restore focus, expect failures and timeouts.
Whiteboard: [stockwell unknown][stockwell needswork:owner] → [stockwell needswork:owner]

The test got timed out because of the window doesn't restore to the original size (it is taller the before), so we can not pass https://searchfox.org/mozilla-central/rev/1479b11f4e861d3e749d284f1d2048713a2dd78f/dom/html/test/file_fullscreen-utils.js#15 checks. This happens only on Window platform, I filed https://bugzilla.mozilla.org/show_bug.cgi?id=1771585.

Depends on: 1771585
Flags: needinfo?(echen)
Status: REOPENED → RESOLVED
Closed: 4 years ago2 years ago
Resolution: --- → DUPLICATE
You need to log in before you can comment on or make changes to this bug.