Closed Bug 1775854 Opened 3 years ago Closed 2 years ago

Intermittent browser/components/newtab/test/browser/browser_customize_menu_content.js | single tracking bug

Categories

(Firefox :: New Tab Page, defect, P2)

defect

Tracking

()

RESOLVED FIXED
116 Branch
Tracking Status
firefox116 --- fixed

People

(Reporter: jmaher, Assigned: Gijs)

References

(Depends on 1 open bug)

Details

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

Attachments

(1 file)

No description provided.

Additional information about this bug failures and frequency patterns can be found by running: ./mach test-info failure-report --bug 1775854

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

  • linux1804-64-asan-qr
  • linux1804-64-qr
  • windows10-32-2004-qr
[task 2023-02-11T10:14:59.264Z] 10:14:59     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is rendered - {} == true - 
[task 2023-02-11T10:14:59.265Z] 10:14:59     INFO - Buffered messages logged at 10:14:52
[task 2023-02-11T10:14:59.265Z] 10:14:59     INFO - Leaving test bound test_render_customizeMenu
[task 2023-02-11T10:14:59.266Z] 10:14:59     INFO - Entering test bound test_open_close_customizeMenu
[task 2023-02-11T10:14:59.266Z] 10:14:59     INFO - Buffered messages finished
[task 2023-02-11T10:14:59.266Z] 10:14:59     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_customize_menu_content.js | Uncaught exception in test bound test_open_close_customizeMenu - "Close button should be focused when menu becomes visible - timed out after 50 tries."
[task 2023-02-11T10:14:59.266Z] 10:14:59     INFO - Leaving test bound test_open_close_customizeMenu
[task 2023-02-11T10:14:59.331Z] 10:14:59     INFO - GECKO(3024) | [Child 3123: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f42e4a27800) [pid = 3123] [serial = 36] [outer = 0] [url = about:blank]
[task 2023-02-11T10:14:59.410Z] 10:14:59     INFO - GECKO(3024) | MEMORY STAT | vsize 3556MB | residentFast 683MB | heapAllocated 372MB
[task 2023-02-11T10:14:59.413Z] 10:14:59     INFO - TEST-OK | browser/components/newtab/test/browser/browser_customize_menu_content.js | took 10200ms
Flags: needinfo?(dharvey)
Whiteboard: [stockwell needswork:owner]

There have been 37 total failures in the last 7 days.
There are:

  • 10 failures on Linux 18.04 x64 WebRender asan opt
  • 26 failures on Linux 18.04 x64 WebRender debug
  • 1 failure on OS X 10.15 WebRender debug

Recent failure log.

[task 2023-03-22T22:30:43.104Z] 22:30:43     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is not rendered - true == true - 
[task 2023-03-22T22:30:43.105Z] 22:30:43     INFO - Buffered messages finished
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is rendered - null == true - got null, expected true (operator ==)
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - Stack trace:
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - test_render_customizeMenu@chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_customize_menu_content.js:106:12
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - async*execute@resource://specialpowers/SpecialPowersSandbox.sys.mjs:138:12
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - _spawnTask@resource://specialpowers/SpecialPowersChild.sys.mjs:1648:15
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - receiveMessage@resource://specialpowers/SpecialPowersChild.sys.mjs:261:21
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - JSActor query*receiveMessage@resource://specialpowers/SpecialPowersParent.sys.mjs:1385:14
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - JSActor query*spawn@resource://specialpowers/SpecialPowersChild.sys.mjs:1579:17
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - testTask@chrome://mochitests/content/browser/browser/components/newtab/test/browser/head.js:374:47
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1039:26
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1111:18
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1253:14
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1028:14
[task 2023-03-22T22:30:43.107Z] 22:30:43     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1053:13
[task 2023-03-22T22:30:43.108Z] 22:30:43     INFO - Leaving test bound test_render_customizeMenu
[task 2023-03-22T22:30:43.108Z] 22:30:43     INFO - Entering test bound test_open_close_customizeMenu
[task 2023-03-22T22:30:45.475Z] 22:30:45     INFO - Leaving test bound test_open_close_customizeMenu
[task 2023-03-22T22:30:45.789Z] 22:30:45     INFO - GECKO(2511) | MEMORY STAT | vsize 20983817MB | residentFast 1624MB
[task 2023-03-22T22:30:45.791Z] 22:30:45     INFO - TEST-OK | browser/components/newtab/test/browser/browser_customize_menu_content.js | took 4690ms
Flags: needinfo?(dharvey)

Hello Dale! Any chance you could help us assign this to someone?
Thank you!

Flags: needinfo?(dharvey)

There have been 34 total failures in the last 7 days.
There are:

  • 6 failures on linux1804-64-asan-qr opt
  • 27 failures on linux1804-64-qr debug and opt
  • 1 failure at macosx1015-64-qr debug

Recent failure log.

Dale, as the owner of this component, can you help us assign the bug to someone? Thank you.

Whiteboard: [stockwell unknown] → [stockwell unknown][stockwell needswork:owner]

Update:

There have been 38 failures within the last 7 days:

  • 9 failures on Linux 18.04 x64 WebRender asan opt
  • 24 failures on Linux 18.04 x64 WebRender debug
  • 1 failure on OS X 10.15 WebRender debug
  • 4 failures on Windows 11 x64 22H2 WebRender debug

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

[task 2023-04-28T06:25:59.575Z] 06:25:59     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is not rendered - true == true - 
[task 2023-04-28T06:25:59.576Z] 06:25:59     INFO - Buffered messages finished
[task 2023-04-28T06:25:59.578Z] 06:25:59     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is rendered - null == true - got null, expected true (operator ==)
[task 2023-04-28T06:25:59.578Z] 06:25:59     INFO - Stack trace:
[task 2023-04-28T06:25:59.578Z] 06:25:59     INFO - test_render_customizeMenu@chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_customize_menu_content.js:106:12
[task 2023-04-28T06:25:59.578Z] 06:25:59     INFO - async*execute@resource://specialpowers/SpecialPowersSandbox.sys.mjs:139:12
[task 2023-04-28T06:25:59.578Z] 06:25:59     INFO - _spawnTask@resource://specialpowers/SpecialPowersChild.sys.mjs:1648:15
[task 2023-04-28T06:25:59.579Z] 06:25:59     INFO - receiveMessage@resource://specialpowers/SpecialPowersChild.sys.mjs:261:21
[task 2023-04-28T06:25:59.579Z] 06:25:59     INFO - JSActor query*receiveMessage@resource://specialpowers/SpecialPowersParent.sys.mjs:1388:14
[task 2023-04-28T06:25:59.579Z] 06:25:59     INFO - JSActor query*spawn@resource://specialpowers/SpecialPowersChild.sys.mjs:1579:17
[task 2023-04-28T06:25:59.579Z] 06:25:59     INFO - testTask@chrome://mochitests/content/browser/browser/components/newtab/test/browser/head.js:376:47
[task 2023-04-28T06:25:59.579Z] 06:25:59     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1133:26
[task 2023-04-28T06:25:59.580Z] 06:25:59     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1205:18
[task 2023-04-28T06:25:59.581Z] 06:25:59     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1347:14
[task 2023-04-28T06:25:59.581Z] 06:25:59     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1122:14
[task 2023-04-28T06:25:59.582Z] 06:25:59     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-04-28T06:25:59.584Z] 06:25:59     INFO - Leaving test bound test_render_customizeMenu
[task 2023-04-28T06:25:59.585Z] 06:25:59     INFO - Entering test bound test_open_close_customizeMenu

Amy, as the owner of this component, can you please help us assign this to someone?
Thank you.

Flags: needinfo?(achurchwell)
Whiteboard: [stockwell unknown][stockwell unknown] → [stockwell needswork:owner]

There have been 36 total failures in the last 7 days.
There are:

  • 30 failures on Linux 18.04 x64 WebRender debug
  • 4 failures on Linux 18.04 x64 WebRender opt
  • 1 failure on Windows 11 x86 22H2 WebRender debug
  • 1 failure on OS X 10.15 WebRender debug

There is a failure line that was classified with this bug: Highlights section is rendered - null == true - got null, expected true (operator ==).

Recent failure log.

[task 2023-05-06T09:59:10.666Z] 09:59:10     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is not rendered - true == true - 
[task 2023-05-06T09:59:10.666Z] 09:59:10     INFO - Buffered messages finished
[task 2023-05-06T09:59:10.668Z] 09:59:10     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is rendered - null == true - got null, expected true (operator ==)
[task 2023-05-06T09:59:10.668Z] 09:59:10     INFO - Stack trace:
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - test_render_customizeMenu@chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_customize_menu_content.js:106:12
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - async*execute@resource://specialpowers/SpecialPowersSandbox.sys.mjs:139:12
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - _spawnTask@resource://specialpowers/SpecialPowersChild.sys.mjs:1648:15
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - receiveMessage@resource://specialpowers/SpecialPowersChild.sys.mjs:261:21
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - JSActor query*receiveMessage@resource://specialpowers/SpecialPowersParent.sys.mjs:1388:14
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - JSActor query*spawn@resource://specialpowers/SpecialPowersChild.sys.mjs:1579:17
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - testTask@chrome://mochitests/content/browser/browser/components/newtab/test/browser/head.js:376:47
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1133:26
[task 2023-05-06T09:59:10.669Z] 09:59:10     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1205:18
[task 2023-05-06T09:59:10.670Z] 09:59:10     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1347:14
[task 2023-05-06T09:59:10.670Z] 09:59:10     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1122:14
[task 2023-05-06T09:59:10.670Z] 09:59:10     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-05-06T09:59:10.672Z] 09:59:10     INFO - Leaving test bound test_render_customizeMenu
[task 2023-05-06T09:59:10.678Z] 09:59:10     INFO - Entering test bound test_open_close_customizeMenu
[task 2023-05-06T09:59:10.799Z] 09:59:10     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f5877b5f800 == 3 [pid = 4673] [id = 63]
[task 2023-05-06T09:59:10.799Z] 09:59:10     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (7f5877a7f660) [pid = 4673] [serial = 190] [outer = 0]
[task 2023-05-06T09:59:10.804Z] 09:59:10     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (7f5877b5fc00) [pid = 4673] [serial = 191] [outer = 7f5877a7f660]
[task 2023-05-06T09:59:11.059Z] 09:59:11     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f5877b65c00) [pid = 4673] [serial = 192] [outer = 7f5877a7f660]
[task 2023-05-06T09:59:11.875Z] 09:59:11     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a4cbf1800 == 8 [pid = 4448] [id = 49] [url = chrome://browser/content/browser.xhtml]
[task 2023-05-06T09:59:11.878Z] 09:59:11     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a4cbf6c00 == 7 [pid = 4448] [id = 50] [url = about:blank]
[task 2023-05-06T09:59:11.878Z] 09:59:11     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a4cbea800 == 6 [pid = 4448] [id = 51] [url = chrome://browser/content/browser.xhtml]
[task 2023-05-06T09:59:11.880Z] 09:59:11     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f6a4e5c6c00 == 5 [pid = 4448] [id = 52] [url = about:blank]
[task 2023-05-06T09:59:12.368Z] 09:59:12     INFO - GECKO(4448) | [Child 4673, Main Thread] WARNING: PuppetWidget::GetInputContext() needs to retrieve it with IPC: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:727
[task 2023-05-06T09:59:12.761Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-05-06T09:59:12.764Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448, Main Thread] WARNING: '!top', file /builds/worker/checkouts/gecko/dom/xul/MenuBarListener.cpp:99
[task 2023-05-06T09:59:12.804Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 17 (7f6a4dfdf280) [pid = 4448] [serial = 123] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:12.804Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 16 (7f6a496d93f0) [pid = 4448] [serial = 119] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:12.810Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 15 (7f6a44dc8840) [pid = 4448] [serial = 121] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2023-05-06T09:59:12.812Z] 09:59:12     INFO - GECKO(4448) | [Parent 4448: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 14 (7f6a45b18c30) [pid = 4448] [serial = 117] [outer = 0] [url = chrome://browser/content/browser.xhtml]
[task 2023-05-06T09:59:12.909Z] 09:59:12     INFO - Leaving test bound test_open_close_customizeMenu
[task 2023-05-06T09:59:13.173Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f5877b58c00 == 2 [pid = 4673] [id = 61] [url = about:newtab]
[task 2023-05-06T09:59:13.176Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 10 (7f5877b59400) [pid = 4673] [serial = 185] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.176Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 9 (7f5878158000) [pid = 4673] [serial = 182] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.176Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 8 (7f5878158c00) [pid = 4673] [serial = 183] [outer = 0] [url = about:newtab]
[task 2023-05-06T09:59:13.178Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f5877b60400 == 1 [pid = 4673] [id = 62] [url = about:newtab]
[task 2023-05-06T09:59:13.235Z] 09:59:13     INFO - GECKO(4448) | [Child 4622: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7ff337a39800) [pid = 4622] [serial = 24] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.296Z] 09:59:13     INFO - GECKO(4448) | [Child 4563: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f2d35097c00) [pid = 4563] [serial = 51] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.297Z] 09:59:13     INFO - GECKO(4448) | [Child 4563: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 1 (7f2d35093800) [pid = 4563] [serial = 54] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.300Z] 09:59:13     INFO - GECKO(4448) | [Child 4563: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 0 (7f2d35089800) [pid = 4563] [serial = 52] [outer = 0] [url = about:blank]
[task 2023-05-06T09:59:13.357Z] 09:59:13     INFO - GECKO(4448) | [Child 4673: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 7 (7f5877a80010) [pid = 4673] [serial = 184] [outer = 0] [url = about:newtab]
[task 2023-05-06T09:59:13.380Z] 09:59:13     INFO - GECKO(4448) | MEMORY STAT | vsize 11844MB | residentFast 688MB | heapAllocated 370MB
[task 2023-05-06T09:59:13.382Z] 09:59:13     INFO - TEST-OK | browser/components/newtab/test/browser/browser_customize_menu_content.js | took 6085ms

There have been 52 total failures in the last 7 days.
There are:

  • 12 failures on linux1804-64-asan-qr opt
  • 33 failures on linux1804-64-qr debug
  • 1 failure on macosx1015-64-qr debug
  • 1 failure on windows11-32-2009-qr debug
  • 5 failures on windows11-64-2009-qr debug

Recent failure log.

Clearing needinfos as triage owner has switched (apologies for any late replies, was not overly familiar with this area of code)

Flags: needinfo?(dharvey)

There have been 57 total failures in the last 7 days.
There are:

  • 11 failures on Linux 18.04 x64 WebRender asan opt
  • 43 failures on Linux 18.04 x64 WebRender debug
  • 1 failure on Windows 11 x86 22H2 WebRender debug
  • 2 failures on Windows 11 x64 22H2 WebRender debug

Recent failure log.


[task 2023-05-29T19:25:14.595Z] 19:25:14     INFO - TEST-PASS | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is not rendered - true == true - 
[task 2023-05-29T19:25:14.595Z] 19:25:14     INFO - Buffered messages finished
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - TEST-UNEXPECTED-FAIL | browser/components/newtab/test/browser/browser_customize_menu_content.js | Highlights section is rendered - null == true - got null, expected true (operator ==)
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - Stack trace:
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - test_render_customizeMenu@chrome://mochitests/content/browser/browser/components/newtab/test/browser/browser_customize_menu_content.js:106:12
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - async*execute@resource://testing-common/SpecialPowersSandbox.sys.mjs:139:12
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - _spawnTask@resource://testing-common/SpecialPowersChild.sys.mjs:1656:15
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - receiveMessage@resource://testing-common/SpecialPowersChild.sys.mjs:263:21
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - JSActor query*receiveMessage@resource://testing-common/SpecialPowersParent.sys.mjs:1400:14
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - JSActor query*spawn@resource://testing-common/SpecialPowersChild.sys.mjs:1585:17
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - testTask@chrome://mochitests/content/browser/browser/components/newtab/test/browser/head.js:376:47
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - async*handleTask@chrome://mochikit/content/browser-test.js:1133:26
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - _runTaskBasedTest@chrome://mochikit/content/browser-test.js:1205:18
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - Tester_execTest@chrome://mochikit/content/browser-test.js:1347:14
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - nextTest/<@chrome://mochikit/content/browser-test.js:1122:14
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - SimpleTest.waitForFocus/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:1056:13
[task 2023-05-29T19:25:14.599Z] 19:25:14     INFO - Leaving test bound test_render_customizeMenu
[task 2023-05-29T19:25:14.600Z] 19:25:14     INFO - Entering test bound test_open_close_customizeMenu
[task 2023-05-29T19:25:14.600Z] 19:25:14     INFO - GECKO(1581) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7ff7b0f38800) [pid = 1778] [serial = 22] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.601Z] 19:25:14     INFO - GECKO(1581) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7ff7b0f3a800) [pid = 1778] [serial = 26] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.602Z] 19:25:14     INFO - GECKO(1581) | [Child 1778: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7ff7b0f39000) [pid = 1778] [serial = 24] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.604Z] 19:25:14     INFO - GECKO(1581) | [Child 1825: Main Thread]: I/DocShellAndDOMWindowLeak ++DOCSHELL 7f1a6e812800 == 4 [pid = 1825] [id = 66]
[task 2023-05-29T19:25:14.605Z] 19:25:14     INFO - GECKO(1581) | [Child 1825: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 9 (7f1a6efaf4e0) [pid = 1825] [serial = 199] [outer = 0]
[task 2023-05-29T19:25:14.605Z] 19:25:14     INFO - GECKO(1581) | [Child 1825: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 10 (7f1a6e818000) [pid = 1825] [serial = 200] [outer = 7f1a6efaf4e0]
[task 2023-05-29T19:25:14.606Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 6 (7f3323a19800) [pid = 1718] [serial = 52] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.607Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 5 (7f3323a26800) [pid = 1718] [serial = 51] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.609Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 4 (7f3323a19400) [pid = 1718] [serial = 49] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.609Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 3 (7f3323a25000) [pid = 1718] [serial = 48] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.610Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOMWINDOW == 2 (7f3323a23c00) [pid = 1718] [serial = 46] [outer = 0] [url = about:blank]
[task 2023-05-29T19:25:14.610Z] 19:25:14     INFO - GECKO(1581) | [Parent 1581, Main Thread] WARNING: NS_ENSURE_TRUE(mNameHashtable.Get(aName, &index)) failed: file /builds/worker/checkouts/gecko/storage/mozStorageRow.cpp:91
[task 2023-05-29T19:25:14.610Z] 19:25:14     INFO - GECKO(1581) | [Child 1718: Main Thread]: I/DocShellAndDOMWindowLeak --DOCSHELL 7f3323a27800 == 0 [pid = 1718] [id = 21] [url = about:blank]
[task 2023-05-29T19:25:14.622Z] 19:25:14     INFO - GECKO(1581) | [Child 1825: Main Thread]: I/DocShellAndDOMWindowLeak ++DOMWINDOW == 11 (7f1a714b5c00) [pid = 1825] [serial = 201] [outer = 7f1a6efaf4e0]
[task 2023-05-29T19:25:15.912Z] 19:25:15     INFO - GECKO(1581) | [Child 1825, Main Thread] WARNING: PuppetWidget::GetInputContext() needs to retrieve it with IPC: file /builds/worker/checkouts/gecko/widget/PuppetWidget.cpp:724
[task 2023-05-29T19:25:16.576Z] 19:25:16     INFO - Leaving test bound test_open_close_customizeMenu
[task 2023-05-29T19:25:17.059Z] 19:25:17     INFO - GECKO(1581) | MEMORY STAT | vsize 11878MB | residentFast 744MB | heapAllocated 417MB
[task 2023-05-29T19:25:17.060Z] 19:25:17     INFO - TEST-OK | browser/components/newtab/test/browser/browser_customize_menu_content.js | took 5255ms

The failure rate here means we should try to prioritize this.

I think this test is rigged. Specifically, the test repeats this pattern:

    prefPromise = ContentTaskUtils.waitForCondition(
      () => Services.prefs.getBoolPref(TOPSTORIES_PREF),
      "Pocket pref is turned on"
    );
    pocketSwitch.click();
    await prefPromise;

What this ends up meaning is the code does the following:

  • check if the pref is flipped (it's not)
  • set a timeout for 100ms or whatever the default is, to check again
  • click the pocketSwitch thing which (I expect) synchronously changes the preference, but presumably asynchronously updates the DOM in response to the preference changing.
  • wait for the timeout, which will find the pref is flipped

Whether or not the DOM has updated is entirely dependent on whether we were able to do whatever async work powers the checkboxes in the new tab settings within the 100ms or whatever.

The failures are intermittent now, but if I change this code to actually await only the pref changing (using a preference observer), the test fails 100% of the time for me on opt builds.

The test should instead be awaiting the sections being rendered, if that is async. I can fix this.

Assignee: nobody → gijskruitbosch+bugs
Severity: -- → S2
Flags: needinfo?(achurchwell)
Priority: P3 → P2
Pushed by gijskruitbosch@gmail.com: https://hg.mozilla.org/integration/autoland/rev/2267e2cb8321 make browser_customize_menu_content.js wait for sections to appear explicitly, r=aminomancer
Status: NEW → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Target Milestone: --- → 116 Branch
Flags: qe-verify-
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: