Closed Bug 1332040 Opened 7 years ago Closed 6 years ago

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

Categories

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

Product:
Core
Component:
DOM: Core & HTML
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
mozilla62
Tracking Flags:
Tracking Status
firefox62 --- fixed

People

(Reporter: intermittent-bug-filer, Assigned: bdahl)

References

(Blocks 1 open bug)

Details

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

Attachments

(2 files)

disable test on linux until a better fix is in place
957 bytes, patch
xidorn
: review-
Details | Diff | Splinter Review
Bug 1332040 - Dispatch resize after fullscreen transistion for headless.
59 bytes, text/x-review-board-request
jrmuizel
: review+
Details 
We disabled part of this test in bug 1330553.
See Also: → 1330553
Failures are Linux and e10s. Logs end consistently like:

[task 2017-03-27T22:09:56.747178Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [denied] Should not grant request in long-running event handler. 
[task 2017-03-27T22:09:56.749132Z] 22:09:56     INFO - Run test file_fullscreen-api.html
[task 2017-03-27T22:09:56.751102Z] 22:09:56     INFO - Buffered messages logged at 22:04:56
[task 2017-03-27T22:09:56.752986Z] 22:09:56     INFO - must wait for focus
[task 2017-03-27T22:09:56.754850Z] 22:09:56     INFO - must wait for focus
[task 2017-03-27T22:09:56.756768Z] 22:09:56     INFO - Buffered messages logged at 22:04:57
[task 2017-03-27T22:09:56.759007Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/XML/1998/namespace 
[task 2017-03-27T22:09:56.761359Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/1999/xlink 
[task 2017-03-27T22:09:56.763579Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/2000/svg 
[task 2017-03-27T22:09:56.766139Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/1998/Math/MathML 
[task 2017-03-27T22:09:56.768269Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'mathml' in this namespace should not be allowed: unknown 
[task 2017-03-27T22:09:56.770405Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'svg' in this namespace should not be allowed: unknown 
[task 2017-03-27T22:09:56.772447Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Document should be in fullscreen 
[task 2017-03-27T22:09:56.774694Z] 22:09:56     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should be allowed: http://www.mozilla.org/keymaster/gatekeeper/there.is.only.xul 
[task 2017-03-27T22:09:56.776569Z] 22:09:56     INFO - Buffered messages finished
[task 2017-03-27T22:09:56.778830Z] 22:09:56     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out.
Whiteboard: [stockwell needswork]
Bug 1350875 is a different failure in the same test that started around 2017-03-27; there is current discussion in that bug.
See Also: → 1350875
Xidorn, you've poked at fullscreen before.  Could you possibly take a look, or suggest someone else who might be able to?
Flags: needinfo?(xidorn+moz)
It's probably the same issue as bug 1350875 which starts appearing since bug 1194751. kanru is currently looking into that bug, and if there's a fix, this may be fixed together.
Blocks: 1215369
Flags: needinfo?(xidorn+moz)
Depends on: 1350875
I reproduced this locally. I think it has different root causes. With my patch in bug 1350875 applied, test case failed because  the fullscreen request is dropped here:

http://searchfox.org/mozilla-central/rev/b8cce081200129a1307e2a682f121135b5ca1d19/dom/base/nsDocument.cpp#11380-11382

where |aElement == GetFullscreenElement()|

I can't look into this further without full understanding about how fullscreen API works.
(In reply to Kan-Ru Chen [:kanru] (UTC-4, EDT) from comment #18)
> I reproduced this locally. I think it has different root causes. With my
> patch in bug 1350875 applied, test case failed because  the fullscreen
> request is dropped here:
> 
> http://searchfox.org/mozilla-central/rev/
> b8cce081200129a1307e2a682f121135b5ca1d19/dom/base/nsDocument.cpp#11380-11382
> 
> where |aElement == GetFullscreenElement()|
> 
> I can't look into this further without full understanding about how
> fullscreen API works.

That sounds like a real issue, then. How can the fullscreen element get changed without the event ever being triggered?
Thanks for working on this :kanru and :xidorn, I am eager to see this fixed as this is our #1 intermittent failure- we are at 2 weeks of high failures- it sounds like you guys are getting close to resolution, how about we give this a few more work days before disabling it temporarily.
Comment on attachment 8856503 [details] [diff] [review]
disable test on linux until a better fix is in place

Review of attachment 8856503 [details] [diff] [review]:
-----------------------------------------------------------------

The patch in bug 1350875 also disables the specific problematic subtest, rather than the whole test.
Attachment #8856503 - Flags: review?(gbrown) → review-
I mean, the specific problematic subtest of this bug in addition to that of bug 1350875.
thanks for doing this and having a better patch in bug 1350875
Whiteboard: [stockwell needswork] → [stockwell disabled]
this started failing yesterday!

https://treeherder.mozilla.org/#/jobs?repo=autoland&filter-searchStr=mochitest-3%20linux&tochange=a2a9dd416dfa785f87859cd07e3b2249eb129fd7&fromchange=e92b4f95ddf030c73484dab2257a7395a3086d67&selectedJob=108813629

ni myself to circle back when results are in
Flags: needinfo?(jmaher)
Whiteboard: [stockwell disabled] → [stockwell needswork]
this looks to be from bug 1355746.

:hchang, as the author of bug 1355746, can you fix this or back your original patch out?
Flags: needinfo?(jmaher) → needinfo?(hchang)
(In reply to Joel Maher ( :jmaher) from comment #33)
> this looks to be from bug 1355746.
> 
> :hchang, as the author of bug 1355746, can you fix this or back your
> original patch out?

The patch has been backed out :( I will look into it today. Do you have 
any idea? My patches mainly modify the background html parsing timing
and seem to have nothing to do with this test.

Thanks :)
Flags: needinfo?(hchang)
I am not familiar at all with this test, but I see that :xidorn has done a lot of work on test_fullscreen-api.html, possibly he could help out.

For reference, here is a recent log file:
https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=109054565&lineNumber=7372

and data from it:
[task 2017-06-22T01:09:14.825430Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :-moz-full-screen pseudo class 
[task 2017-06-22T01:09:14.827428Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should get prefixed event on [object Window] 
[task 2017-06-22T01:09:14.831393Z] 01:09:14     INFO - [prefixed] Check fullscreen state after exit in test case 1
[task 2017-06-22T01:09:14.833448Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2017-06-22T01:09:14.835595Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2017-06-22T01:09:14.837812Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2017-06-22T01:09:14.841569Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2017-06-22T01:09:14.843662Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2017-06-22T01:09:14.845640Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should get prefixed event on [object Window] 
[task 2017-06-22T01:09:14.847526Z] 01:09:14     INFO - [prefixed] Check fullscreen state after failed request in test case 1
[task 2017-06-22T01:09:14.849439Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2017-06-22T01:09:14.851397Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2017-06-22T01:09:14.853441Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2017-06-22T01:09:14.855785Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2017-06-22T01:09:14.858014Z] 01:09:14     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2017-06-22T01:09:14.859931Z] 01:09:14     INFO - Buffered messages finished
[task 2017-06-22T01:09:14.861998Z] 01:09:14     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out. 


this is the last check in the file_fullscreen-prefixed.html subtest:
https://dxr.mozilla.org/mozilla-central/source/dom/html/test/file_fullscreen-prefixed.html?q=path%3Afile_fullscreen-prefixed.html&redirect_type=single#53

it appears we run the first test case, but not the second one.

:xidorn, could you help :hchang figure out why his patch causes this test to fail with timeout as above 50%+ of the time?
Flags: needinfo?(xidorn+moz)
Whiteboard: [stockwell needswork] → [stockwell fixed:backout]
I have no idea why this happens. From the log, it seems that fullscreen event is not dispatched at all in that case. Is there anything changes whether an event is dispatched?
Flags: needinfo?(xidorn+moz)
this was fixed by June 23, I think the backou taccounts for this properly.
Priority: -- → P5
Status: NEW → RESOLVED
Closed: 7 years ago
Resolution: --- → FIXED
Recent failure log:  https://treeherder.mozilla.org/logviewer.html#?job_id=154204977&repo=autoland&lineNumber=8846


[task 2018-01-04T22:24:51.415Z] 22:24:51     INFO - TEST-INFO | started process screentopng
[task 2018-01-04T22:24:51.881Z] 22:24:51     INFO - TEST-INFO | screentopng: exit 0
[task 2018-01-04T22:24:51.885Z] 22:24:51     INFO - <snipped 401 output lines - if you need more context, please use SimpleTest.requestCompleteLog() in your test>
[task 2018-01-04T22:24:51.888Z] 22:24:51     INFO - Buffered messages logged at 22:23:51
[task 2018-01-04T22:24:51.892Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should be in fullscreen 
[task 2018-01-04T22:24:51.896Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be div 
[task 2018-01-04T22:24:51.906Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:51.907Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :fullscreen pseudo class 
[task 2018-01-04T22:24:51.909Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:51.911Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:51.914Z] 22:24:51     INFO - [prefixed] Check fullscreen state after exit in test case 6
[task 2018-01-04T22:24:51.917Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:51.918Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:51.920Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:51.921Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:51.924Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:51.925Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:51.925Z] 22:24:51     INFO - [prefixed] Check fullscreen state after failed request in test case 6
[task 2018-01-04T22:24:51.929Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:51.931Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:51.937Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:51.938Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:51.940Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:51.941Z] 22:24:51     INFO - Buffered messages logged at 22:23:52
[task 2018-01-04T22:24:51.944Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:51.946Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should get prefixed event on [object Window] 
[task 2018-01-04T22:24:51.949Z] 22:24:51     INFO - [prefixed] Check fullscreen state after request in test case 7
[task 2018-01-04T22:24:51.953Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should be in fullscreen 
[task 2018-01-04T22:24:51.956Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be div 
[task 2018-01-04T22:24:51.959Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:51.962Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :fullscreen pseudo class 
[task 2018-01-04T22:24:51.965Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:51.968Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:51.971Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should get prefixed event on [object Window] 
[task 2018-01-04T22:24:51.975Z] 22:24:51     INFO - [prefixed] Check fullscreen state after exit in test case 7
[task 2018-01-04T22:24:51.977Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:51.980Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:51.982Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:51.984Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:51.986Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:51.990Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:51.993Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should get prefixed event on [object Window] 
[task 2018-01-04T22:24:51.996Z] 22:24:51     INFO - [prefixed] Check fullscreen state after failed request in test case 7
[task 2018-01-04T22:24:51.999Z] 22:24:51     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:52.003Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:52.004Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:52.007Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:52.009Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:52.013Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:52.015Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object Window] 
[task 2018-01-04T22:24:52.019Z] 22:24:52     INFO - [prefixed] Check fullscreen state after request in test case 8
[task 2018-01-04T22:24:52.031Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should be in fullscreen 
[task 2018-01-04T22:24:52.036Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be div 
[task 2018-01-04T22:24:52.041Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:52.045Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :fullscreen pseudo class 
[task 2018-01-04T22:24:52.048Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:52.052Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:52.056Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object Window] 
[task 2018-01-04T22:24:52.058Z] 22:24:52     INFO - [prefixed] Check fullscreen state after exit in test case 8
[task 2018-01-04T22:24:52.061Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:52.064Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:52.068Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:52.070Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:52.074Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:52.076Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object HTMLDocument] 
[task 2018-01-04T22:24:52.080Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should only get unprefixed event on [object Window] 
[task 2018-01-04T22:24:52.082Z] 22:24:52     INFO - [prefixed] Check fullscreen state after failed request in test case 8
[task 2018-01-04T22:24:52.085Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Should not be in fullscreen 
[task 2018-01-04T22:24:52.088Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should be null 
[task 2018-01-04T22:24:52.091Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] document.mozFullScreenElement should be identical to fullscreenElement 
[task 2018-01-04T22:24:52.094Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :fullscreen pseudo class 
[task 2018-01-04T22:24:52.098Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [prefixed] Fullscreen element should not match :-moz-full-screen pseudo class 
[task 2018-01-04T22:24:52.101Z] 22:24:52     INFO - Run test file_fullscreen-unprefix-disabled.html
[task 2018-01-04T22:24:52.103Z] 22:24:52     INFO - Buffered messages logged at 22:23:55
[task 2018-01-04T22:24:52.106Z] 22:24:52     INFO - must wait for focus
[task 2018-01-04T22:24:52.108Z] 22:24:52     INFO - must wait for focus
[task 2018-01-04T22:24:52.112Z] 22:24:52     INFO - Buffered messages logged at 22:24:23
[task 2018-01-04T22:24:52.114Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | A valid string reason is expected 
[task 2018-01-04T22:24:52.115Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | Reason cannot be empty 
[task 2018-01-04T22:24:52.118Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No element.requestFullscreen 
[task 2018-01-04T22:24:52.120Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.exitFullscreen 
[task 2018-01-04T22:24:52.121Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.fullscreen 
[task 2018-01-04T22:24:52.123Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.fullscreenElement 
[task 2018-01-04T22:24:52.124Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.fullscreenEnabled 
[task 2018-01-04T22:24:52.126Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.onfullscreenchange 
[task 2018-01-04T22:24:52.127Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] No document.onfullscreenerror 
[task 2018-01-04T22:24:52.129Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get custom event from document only once 
[task 2018-01-04T22:24:52.131Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the desired custom event 
[task 2018-01-04T22:24:52.133Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get custom event from window only once 
[task 2018-01-04T22:24:52.137Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the desired custom event 
[task 2018-01-04T22:24:52.139Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the custom event from window 
[task 2018-01-04T22:24:52.140Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the custom event from document 
[task 2018-01-04T22:24:52.142Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get custom event from document only once 
[task 2018-01-04T22:24:52.143Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the desired custom event 
[task 2018-01-04T22:24:52.145Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get custom event from window only once 
[task 2018-01-04T22:24:52.146Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the desired custom event 
[task 2018-01-04T22:24:52.148Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the custom event from window 
[task 2018-01-04T22:24:52.150Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] Should get the custom event from document 
[task 2018-01-04T22:24:52.156Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [unprefix-disabled] :fullscreen should not apply 
[task 2018-01-04T22:24:52.158Z] 22:24:52     INFO - TEST-FAIL | dom/html/test/test_fullscreen-api.html | The author of the test has indicated that flaky timeouts are expected.  Reason: need to wait for a while to confirm no unexpected event is dispatched 
[task 2018-01-04T22:24:52.161Z] 22:24:52     INFO - Run test file_fullscreen-lenient-setters.html
[task 2018-01-04T22:24:52.162Z] 22:24:52     INFO - Buffered messages logged at 22:24:38
[task 2018-01-04T22:24:52.164Z] 22:24:52     INFO - must wait for focus
[task 2018-01-04T22:24:52.165Z] 22:24:52     INFO - must wait for focus
[task 2018-01-04T22:24:52.168Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreen should not be changed 
[task 2018-01-04T22:24:52.169Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreenElement should not be changed 
[task 2018-01-04T22:24:52.171Z] 22:24:52     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [lenient-setters] fullscreenEnabled should not be changed 
[task 2018-01-04T22:24:52.172Z] 22:24:52     INFO - Run test file_fullscreen-table.html
[task 2018-01-04T22:24:52.174Z] 22:24:52     INFO - Buffered messages finished
[task 2018-01-04T22:24:52.176Z] 22:24:52     INFO - TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out. 
[task 2018-01-04T22:24:52.177Z] 22:24:52     INFO -     reportError@SimpleTest/TestRunner.js:121:7
[task 2018-01-04T22:24:52.178Z] 22:24:52     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2018-01-04T22:24:52.180Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.181Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.182Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.184Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.187Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.188Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.189Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.190Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.192Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.193Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.194Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.196Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.197Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.198Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.199Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.199Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.200Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.203Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.204Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.204Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.205Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.206Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.207Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.208Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.210Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.211Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.213Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.214Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.215Z] 22:24:52     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2018-01-04T22:24:52.218Z] 22:24:52     INFO -     TestRunner.runTests@SimpleTest/TestRunner.js:380:5
[task 2018-01-04T22:24:52.219Z] 22:24:52     INFO -     RunSet.runtests@SimpleTest/setup.js:194:3
[task 2018-01-04T22:24:52.220Z] 22:24:52     INFO -     RunSet.runall@SimpleTest/setup.js:173:5
[task 2018-01-04T22:24:52.222Z] 22:24:52     INFO -     hookupTests@SimpleTest/setup.js:266:5
[task 2018-01-04T22:24:52.224Z] 22:24:52     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
[task 2018-01-04T22:24:52.225Z] 22:24:52     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
[task 2018-01-04T22:24:52.227Z] 22:24:52     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
[task 2018-01-04T22:24:52.228Z] 22:24:52     INFO -     hookup@SimpleTest/setup.js:246:5
[task 2018-01-04T22:24:52.230Z] 22:24:52     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2018-01-04T22:24:52.232Z] 22:24:52     INFO - GECKO(1464) | --DOMWINDOW == 11 (0x7fddfe12ec00) [pid = 1523] [serial = 1271] [outer = (nil)] [url = about:blank]
[task 2018-01-04T22:24:52.432Z] 22:24:52     INFO - GECKO(1464) | MEMORY STAT | vsize 1929MB | residentFast 188MB | heapAllocated 30MB
[task 2018-01-04T22:24:53.809Z] 22:24:53     INFO - TEST-OK | dom/html/test/test_fullscreen-api.html | took 303665ms
[task 2018-01-04T22:24:54.212Z] 22:24:54     INFO - GECKO(1464) | [Child 1523, Main Thread] WARNING: Unable to find interface object on global: file /builds/worker/workspace/build/src/dom/base/nsDOMClassInfo.cpp, line 1746
[task 2018-01-04T22:24:54.395Z] 22:24:54     INFO - must wait for focus
[task 2018-01-04T22:24:54.517Z] 22:24:54     INFO - must wait for focus
[task 2018-01-04T22:24:54.970Z] 22:24:54     INFO - [table] The default background of window should be white
[task 2018-01-04T22:24:55.015Z] 22:24:55     INFO - TEST-PASS | dom/html/test/test_fullscreen-api.html | [table] reftest comparison: == snapshot white 
[task 2018-01-04T22:24:55.318Z] 22:24:55    ERROR - /tests/dom/html/test/test_fullscreen-api.html logged result after SimpleTest.finish(): [table] reftest comparison: == snapshot white
[task 2018-01-04T22:24:55.358Z] 22:24:55     INFO - GECKO(1464) | ++DOMWINDOW == 12 (0x7fddfe137c00) [pid = 1523] [serial = 1276] [outer = 0x7fde11481a40]
Status: RESOLVED → REOPENED
Resolution: FIXED → ---
Over the last 7 days this bug has 30 failures. These only happen on windows10-64.
Here is the most recent log example: https://treeherder.mozilla.org/logviewer.html#?job_id=178888049&repo=mozilla-inbound&lineNumber=16579


00:25:30     INFO -  Buffered messages logged at 00:20:23
00:25:30     INFO -  4442 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [denied] Should not grant request in long-running event handler.
00:25:30     INFO -  4443 INFO Run test file_fullscreen-api.html
00:25:30     INFO -  Buffered messages logged at 00:20:24
00:25:30     INFO -  4444 INFO must wait for focus
00:25:30     INFO -  4445 INFO must wait for focus
00:25:30     INFO -  4446 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/XML/1998/namespace
00:25:30     INFO -  4447 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/1999/xlink
00:25:30     INFO -  4448 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/2000/svg
00:25:30     INFO -  4449 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should not be allowed: http://www.w3.org/1998/Math/MathML
00:25:30     INFO -  4450 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'mathml' in this namespace should not be allowed: unknown
00:25:30     INFO -  4451 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'svg' in this namespace should not be allowed: unknown
00:25:30     INFO -  4452 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Document should be in fullscreen
00:25:30     INFO -  4453 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should be allowed: http://www.mozilla.org/keymaster/gatekeeper/there.is.only.xul
00:25:30     INFO -  4454 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Document should be in fullscreen
00:25:30     INFO -  4455 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'element' in this namespace should be allowed: http://www.w3.org/1999/xhtml
00:25:30     INFO -  4456 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Document should be in fullscreen
00:25:30     INFO -  4457 INFO TEST-PASS | dom/html/test/test_fullscreen-api.html | [fullscreen] Element named 'svg' in this namespace should be allowed: http://www.w3.org/1999/xhtml
00:25:30     INFO -  Buffered messages finished
00:25:30    ERROR -  4458 INFO TEST-UNEXPECTED-FAIL | dom/html/test/test_fullscreen-api.html | Test timed out.
00:25:30     INFO -      reportError@SimpleTest/TestRunner.js:121:7
00:25:30     INFO -      TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
Flags: needinfo?(overholt)
This looks like a regression somewhere, since this test didn't fail on windows.

Could you help bisect and figure out which push caused this intermittent?
Flags: needinfo?(shindli)
Actually, this issue only happens in headless mochitest, not normal mochitest, so it may be some issue in the headless implementation...
Okay, so should I still look into where this starts?
Flags: needinfo?(shindli)
Yeah, I think that would still be helpful. And also probably we should ni? someone familiar with headless stuff.
This is the earliest failure that I could find that's related to this regression: https://treeherder.mozilla.org/#/jobs?repo=autoland&revision=4e1b874e708bd762f1cb1cdf87edcc3a07bc1d9e&selectedJob=178641706
As you said, we should ask someone familiar with headless stuff to see what's really happening here.
I think :bdahl did a lot of headless stuff
Flags: needinfo?(bdahl)
Flags: needinfo?(overholt)
I think I have a fix. Doing some final testing now.
Assignee: nobody → bdahl
Flags: needinfo?(bdahl)
(In reply to Brendan Dahl [:bdahl] from comment #63)
> I was able to reproduce reliably locally. Also, try run is looking good
> https://treeherder.mozilla.org/#/
> jobs?repo=try&revision=a53982b76b3556e2540363424a300f0e18569ec2&selectedJob=1
> 79591666

Uhg...looks like a different intermittent failure now.
Comment on attachment 8979696 [details]
Bug 1332040 - Dispatch resize after fullscreen transistion for headless.

https://reviewboard.mozilla.org/r/245846/#review251984
Attachment #8979696 - Flags: review?(jmuizelaar) → review+
Pushed by bdahl@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/ae756063903d
Dispatch resize after fullscreen transistion for headless. r=jrmuizel
https://hg.mozilla.org/mozilla-central/rev/ae756063903d
Status: REOPENED → RESOLVED
Closed: 7 years ago → 6 years ago
status-firefox62: --- → fixed
Resolution: --- → FIXED
Target Milestone: --- → mozilla62
FWIW, I don't think my fix was the proper full fix. I'll open another bug Tuesday explaining what I think is going on (it's not headless specific).
Component: DOM → DOM: Core & HTML
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: