1691892 - Intermittent browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Test timed out - | Found a tab after previous test timed out: http://example.com/browser/browser/base/content/test/fullscreen/open_and_focus_helper.html

Status: RESOLVED FIXED

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

Description

[Henri Sivonen (:hsivonen)]

[task 2021-02-10T03:52:56.430Z] 03:52:56     INFO - GECKO(2571) | [Parent 2571: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 18 (0x7f2e3bc4bac0) [pid = 2571] [serial = 18] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:52:57.069Z] 03:52:57     INFO - GECKO(2571) | [Child 2628: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (0x7f320d9bc800) [pid = 2628] [serial = 11] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:52:59.098Z] 03:52:59     INFO - GECKO(2571) | [Child 3147: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 0x7f5e6a110800 == 1 [pid = 3147] [id = 1] [url = about:blank]
[task 2021-02-10T03:52:59.126Z] 03:52:59     INFO - GECKO(2571) | [Child 3147: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (0x7f5e6a161200) [pid = 3147] [serial = 4] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:00.407Z] 03:53:00     INFO - GECKO(2571) | [Parent 2571: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (0x7f2e32ee6c00) [pid = 2571] [serial = 17] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:00.407Z] 03:53:00     INFO - GECKO(2571) | [Parent 2571: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (0x7f2e32d2d800) [pid = 2571] [serial = 19] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:00.408Z] 03:53:00     INFO - GECKO(2571) | [Parent 2571: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (0x7f2e401f2c00) [pid = 2571] [serial = 12] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:00.408Z] 03:53:00     INFO - GECKO(2571) | [Parent 2571: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (0x7f2e401f5000) [pid = 2571] [serial = 13] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:03.197Z] 03:53:03     INFO - GECKO(2571) | [Child 3147: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (0x7f5e6a109800) [pid = 3147] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:03.199Z] 03:53:03     INFO - GECKO(2571) | [Child 3147: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (0x7f5e6a111c00) [pid = 3147] [serial = 5] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:03.200Z] 03:53:03     INFO - GECKO(2571) | [Child 3147: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f5e6a1e4800) [pid = 3147] [serial = 6] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:53:04.875Z] 03:53:04     INFO - GECKO(2571) | [Child 3164: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (0x7f3a6c409c00) [pid = 3164] [serial = 2] [outer = (nil)] [url = about:blank]
[task 2021-02-10T03:54:17.274Z] 03:54:17     INFO - TEST-INFO | started process screentopng
[task 2021-02-10T03:54:17.597Z] 03:54:17     INFO - TEST-INFO | screentopng: exit 0
[task 2021-02-10T03:54:17.598Z] 03:54:17     INFO - Buffered messages logged at 03:52:47
[task 2021-02-10T03:54:17.599Z] 03:54:17     INFO - Entering test bound setup
[task 2021-02-10T03:54:17.600Z] 03:54:17     INFO - Leaving test bound setup
[task 2021-02-10T03:54:17.601Z] 03:54:17     INFO - Entering test bound test_parentWindowFocus
[task 2021-02-10T03:54:17.601Z] 03:54:17     INFO - Calling window.open()
[task 2021-02-10T03:54:17.602Z] 03:54:17     INFO - Buffered messages logged at 03:52:48
[task 2021-02-10T03:54:17.603Z] 03:54:17     INFO - re-focusing main window
[task 2021-02-10T03:54:17.603Z] 03:54:17     INFO - must wait for focus
[task 2021-02-10T03:54:17.604Z] 03:54:17     INFO - Entering full-screen
[task 2021-02-10T03:54:17.605Z] 03:54:17     INFO - Buffered messages logged at 03:52:49
[task 2021-02-10T03:54:17.605Z] 03:54:17     INFO - Calling window.focus()
[task 2021-02-10T03:54:17.606Z] 03:54:17     INFO - Console message: [JavaScript Warning: "Exited fullscreen because a window was focused." {file: "http://example.com/browser/browser/base/content/test/fullscreen/open_and_focus_helper.html" line: 0}]
[task 2021-02-10T03:54:17.606Z] 03:54:17     INFO - Buffered messages logged at 03:52:50
[task 2021-02-10T03:54:17.607Z] 03:54:17     INFO - TEST-PASS | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Should leave full-screen - 
[task 2021-02-10T03:54:17.608Z] 03:54:17     INFO - Leaving test bound test_parentWindowFocus
[task 2021-02-10T03:54:17.608Z] 03:54:17     INFO - Entering test bound test_iframeWindowFocus
[task 2021-02-10T03:54:17.609Z] 03:54:17     INFO - Calling window.open()
[task 2021-02-10T03:54:17.610Z] 03:54:17     INFO - Buffered messages logged at 03:52:52
[task 2021-02-10T03:54:17.610Z] 03:54:17     INFO - re-focusing main window
[task 2021-02-10T03:54:17.611Z] 03:54:17     INFO - must wait for focus
[task 2021-02-10T03:54:17.611Z] 03:54:17     INFO - Entering full-screen
[task 2021-02-10T03:54:17.612Z] 03:54:17     INFO - Console message: [JavaScript Warning: "Request for fullscreen was denied because requesting element is not in the currently focused tab." {file: "http://example.com/browser/browser/base/content/test/fullscreen/open_and_focus_helper.html" line: 0}]
[task 2021-02-10T03:54:17.613Z] 03:54:17     INFO - Console message: [JavaScript Error: "TypeError: Fullscreen request denied"]
[task 2021-02-10T03:54:17.613Z] 03:54:17     INFO - Buffered messages finished
[task 2021-02-10T03:54:17.614Z] 03:54:17     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Test timed out - 
[task 2021-02-10T03:54:17.614Z] 03:54:17     INFO - GECKO(2571) | MEMORY STAT | vsize 3165MB | residentFast 346MB | heapAllocated 99MB
[task 2021-02-10T03:54:17.615Z] 03:54:17     INFO - TEST-OK | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | took 90015ms
[task 2021-02-10T03:54:17.615Z] 03:54:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-10T03:54:17.616Z] 03:54:17     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Found a tab after previous test timed out: http://example.com/browser/browser/base/content/test/fullscreen/open_and_focus_helper.html - 
[task 2021-02-10T03:54:17.616Z] 03:54:17     INFO - GECKO(2571) | [Child 2628: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 0x7f320d9bbc00 == 1 [pid = 2628] [id = 4]
[task 2021-02-10T03:54:17.617Z] 03:54:17     INFO - GECKO(2571) | [Child 2628: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 1 (0x7f322a062ac0) [pid = 2628] [serial = 12] [outer = (nil)]
[task 2021-02-10T03:54:17.636Z] 03:54:17     INFO - GECKO(2571) | [Child 2628: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 2 (0x7f320d9bd000) [pid = 2628] [serial = 13] [outer = 0x7f322a062ac0]
[task 2021-02-10T03:54:17.636Z] 03:54:17     INFO - checking window state
[task 2021-02-10T03:54:17.636Z] 03:54:17     INFO - Not taking screenshot here: see the one that was previously logged
[task 2021-02-10T03:54:17.636Z] 03:54:17     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/fullscreen/browser_fullscreen_window_focus.js | Found a browser window after previous test timed out - 

Comment 1

[Chris Peterson [:cpeterson]]

Tentatively tracking for Fission M7 Beta. If this test's intermittent failure rate is very low, then it might not need to block Fission.

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:

• autoland: 3
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64: 4
  • debug: 4

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1691892&startday=2021-02-08&endday=2021-02-14&tree=all

Comment 3

[Henri Sivonen (:hsivonen)]

Making the focused browsing context changes ordered by action id does not fix this:
https://treeherder.mozilla.org/jobs?repo=try&revision=2da09cc906171884369624c0e02b4de566594fe9&selectedTaskRun=DyjyYTM8QSGcCEfiJOJscw.0

Now I don't have immediate ideas what would.

Comment 4

[Intermittent Failures Robot]

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

Repository breakdown:

• try: 4
• autoland: 6
• mozilla-central: 1

Platform and build breakdown:

• linux1804-64: 11
  • debug: 11

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

Comment 5

[Henri Sivonen (:hsivonen)]

I thought I had an rr trace of this, but it was just a timeout happening right after the test had already pretty much passed.

The log in comment 0 shows the message from here:
https://searchfox.org/mozilla-central/rev/f47a4b67643b3048ef9a2e2ac0c34edf6d1ebff3/dom/base/Document.cpp#14241

This suggest that this is still about tab activeness despite the earlier fix to add actionIds to setting the active BrowsingContext.

Comment 6

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=c636ff672b7b5e4b6a3e53bb6ff0860578aca98d

Comment 7

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=d3b0219bc4ec41cd1edaf3776d66cb902c9266e0

Comment 8

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=1a909ec3dbb593c01ce05fb20f0d962e50e06dff

Comment 9

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64: 8
  • debug: 8
• linux1804-64-shippable: 1
  • opt: 1

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

Comment 10

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=8196d6983ac9d189200eca3af9ce9a16bde56d92

Comment 11

[Henri Sivonen (:hsivonen)]

Rebase for Pernosco compat:
https://treeherder.mozilla.org/#/jobs?repo=try&revision=9491f77bc7d268326231f9b41fb192c068bc905e

Comment 12

[Henri Sivonen (:hsivonen)]

With rebase, the test is now in bc12

Comment 13

[Henri Sivonen (:hsivonen)]

Attachment: Bug 1691892 - Reject stale active browsing context setting attempts by action id in content processes.

Comment 14

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=198b1a3b8c334559907b7befea18f37c0f3493dc

Comment 15

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=9565f5a42b80c55762008293265d07119b372b19

Comment 16

[Intermittent Failures Robot]

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

Repository breakdown:

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

Platform and build breakdown:

• linux1804-64: 7
  • debug: 7
• linux1804-64-shippable: 1
  • opt: 1

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

Comment 17

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=993c6c90b258ddf4e095e8592d548dc22dc8c912

Comment 18

[Henri Sivonen (:hsivonen)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=dd7c159f58361c29c5fb7e06b4bad3ae3246dd2f

Comment 19

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/2217b5d0ed86
Reject stale active browsing context setting attempts by action id in content processes. r=nika

Comment 20

[Dorel Luca [:dluca]]

https://hg.mozilla.org/mozilla-central/rev/2217b5d0ed86

Comment 21

[Intermittent Failures Robot]

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

Repository breakdown:

• mozilla-central: 1
• autoland: 2

Platform and build breakdown:

• linux1804-64: 3
  • debug: 3

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1691892&startday=2021-03-08&endday=2021-03-14&tree=all