1638499 - Frequent browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | A promise chain failed to handle a rejection: Waiting for document focus - timed out after 50 tries. - stack: (No stack available.)

Status: RESOLVED FIXED

(Firefox :: General, defect, P5)

Description

[Treeherder Bug Filer]

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

---

[task 2020-05-15T23:52:22.738Z] 23:52:22 INFO - FBO Texture Target: TEXTURE_2D
[task 2020-05-15T23:52:22.738Z] 23:52:22 INFO - Entering full-screen
[task 2020-05-15T23:52:22.738Z] 23:52:22 INFO - Buffered messages logged at 23:51:48
[task 2020-05-15T23:52:22.738Z] 23:52:22 INFO - Console message: [JavaScript Error: "uncaught exception: Waiting for document focus - timed out after 50 tries."]
[task 2020-05-15T23:52:22.738Z] 23:52:22 INFO - Buffered messages finished
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Test timed out -
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - Not taking screenshot here: see the one that was previously logged
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | A promise chain failed to handle a rejection: Waiting for document focus - timed out after 50 tries. - stack: (No stack available.)
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - Rejection date: Fri May 15 2020 23:51:48 GMT+0000 (Greenwich Mean Time) - false == true - JS frame :: resource://testing-common/PromiseTestUtils.jsm :: assertNoUncaughtRejections :: line 265
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - Stack trace:
[task 2020-05-15T23:52:22.739Z] 23:52:22 INFO - resource://testing-common/PromiseTestUtils.jsm:assertNoUncaughtRejections:265
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:nextTest:610
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:timeoutFn:1190
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - setTimeout handler*chrome://mochikit/content/browser-test.js:Tester_execTest:1137
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - chrome://mochikit/content/browser-test.js:nextTest/<:927
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<:918
[task 2020-05-15T23:52:22.740Z] 23:52:22 INFO - GECKO(2055) | MEMORY STAT | vsize 7685MB | residentFast 333MB | heapAllocated 113MB

[task 2020-05-15T23:51:37.570Z] 23:51:37 INFO - TEST-START | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js

Comment 1

[Bogdan Tara[:bogdan_tara | bogdant]]

This seems to have started with the changes on bug 1276537.
https://treeherder.mozilla.org/#/jobs?repo=autoland&searchStr=os%2Cx%2C10.14%2Cshippable%2Copt%2Cmochitests%2Ctest-macosx1014-64-shippable%2Fopt-mochitest-browser-chrome-e10s-2%2Cm%28bc2%29&tochange=ea043dfff6a65516a1925efec246491ba4ec5182&fromchange=4266d097e223610178159331d3ffea2fbacf4ec7&test_paths=browser%2Fbase%2Fcontent%2Ftest%2Ffullscreen%2F

xidorn could you, please, take a look?

Comment 2

[Xidorn Quan [:xidorn] UTC+10]

Interestingly I found this test no longer fails on macOS debug: https://treeherder.mozilla.org/#/jobs?repo=try&revision=b5bb0a2807d1738dae1da6e3d5fabbc8e887c38a

Comment 3

[Intermittent Failures Robot]

18 failures in 5488 pushes (0.003 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 17
• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 18

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

Comment 4

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

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

Comment 5

[Xidorn Quan [:xidorn] UTC+10]

So, I believe the reason is that the popup window somehow gets focused by the system or so after it's de-focused. I have several try pushes with different approaches intended to find a way to work around it, but no luck yet.

There was a try push with an setTimeout to delay going full screen which is almost green, and the only failure seems to be because test itself takes too long, not because any other unexpected situation. But I'm not very comfortable with taking an arbitrary wait time for something happening for unknown reason :(

Comment 6

[Xidorn Quan [:xidorn] UTC+10]

Attachment: Bug 1638499 - Disable browser_fullscreen_window_focus.js on macOS. r?pbz

Comment 7

[Xidorn Quan [:xidorn] UTC+10]

I think the best we can do for now is just to disable it on macOS. The intermittent failure here has the same symptom as described in bug 1568570 that the fullscreen request is denied due to loss of focus, so I think it can be covered by that bug (i.e. no need to keep this bug open).

Actually it seems to me that this may no longer be a problem for debug. None of my experiment debugging try pushes (which have the os == 'mac' condition removed) shows failure on this test with debug build. But I don't think there is anything fundamentally changed... It's probably just that changes in bug 1276537 nudged some timing a little bit...

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/b818588404a5
Disable browser_fullscreen_window_focus.js on macOS. r=pbz

Comment 9

[Dorel Luca [:dluca]]

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

Comment 10

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 6
• mozilla-central: 1

Platform breakdown:

• macosx1014-64-shippable: 7

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