Closed Bug 1784868 Opened 2 years ago Closed 2 years ago

Perma browser/base/content/test/performance/browser_startup_mainthreadio.js | single tracking bug

Categories

(Web Compatibility :: Interventions, defect)

defect

Tracking

(firefox-esr102 unaffected, firefox105 unaffected, firefox106 unaffected, firefox107+ fixed)

RESOLVED FIXED
Tracking Status
firefox-esr102 --- unaffected
firefox105 --- unaffected
firefox106 --- unaffected
firefox107 + fixed

People

(Reporter: jmaher, Assigned: pbz)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression, Whiteboard: [stockwell disable-recommended])

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 1784868

Update:

There have been 39 failures within the last 7 days:

  • 26 failures on Linux 18.04 x64 WebRender opt
  • 7 failures on Linux 18.04 x64 WebRender Shippable opt
  • 5 failures on Windows 10 x64 2004 WebRender opt
  • 1 failure on Windows 10 x64 2004 WebRender Shippable opt

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

:mossop, as the owner of this component, can you help us assign this to someone?

Flags: needinfo?(dtownsend)
Whiteboard: [stockwell needswork:owner]

Failure breakdown:

  • TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected close on /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi before first paint -
  • TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | no main thread IO when we expected some during before opening first browser window: C:\Users\task_166052149588494\AppData\Local\Temp\tmpw0l4tng6.mozrunner (ProfD:) -
Component: General → Interventions
Product: Firefox → Web Compatibility

This should either be added to the allow list in the test, or investigated if we can delay this to after first paint, or even later.

We removed priority because it depends on the answer to the latter question, and we don't know this component well enough to answer it. Could someone from Web Compact check that please?

Severity: S4 → --
Flags: needinfo?(dtownsend)
Priority: P3 → --
Flags: needinfo?(dschubert)

We used to insist on starting as early as possible, but in reality, we know that we're already too late for some sessionrestore sites, so I think delaying our addon's startup would be fine - or at least worth investigating.

What options to we have available here? I'd be happy to implement something and run it through our QA!

Flags: needinfo?(dschubert)

There are 33 total failures in the last 7 days on

[task 2022-09-22T11:53:45.481Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /tmp/tmpq6cfkgbj.mozrunner/user.js as many times as expected before profile selection - 
[task 2022-09-22T11:53:45.482Z] 11:53:45     INFO - known main thread IO paths during before opening first browser window:
[task 2022-09-22T11:53:45.482Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi - stat: 3, close: 2, listedPath: XREAppFeat:formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.483Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi - stat: 3, close: 2, listedPath: XREAppFeat:pictureinpicture@mozilla.org.xpi
[task 2022-09-22T11:53:45.483Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi - stat: 3, close: 2, listedPath: XREAppFeat:screenshots@mozilla.org.xpi
[task 2022-09-22T11:53:45.484Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi - stat: 3, close: 2, listedPath: XREAppFeat:webcompat-reporter@mozilla.org.xpi
[task 2022-09-22T11:53:45.484Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi - stat: 3, close: 2, listedPath: XREAppFeat:webcompat@mozilla.org.xpi
[task 2022-09-22T11:53:45.485Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.485Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.485Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.485Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi
[task 2022-09-22T11:53:45.486Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi
[task 2022-09-22T11:53:45.487Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi
[task 2022-09-22T11:53:45.488Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi
[task 2022-09-22T11:53:45.488Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi
[task 2022-09-22T11:53:45.489Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi
[task 2022-09-22T11:53:45.490Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi
[task 2022-09-22T11:53:45.490Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi
[task 2022-09-22T11:53:45.491Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi
[task 2022-09-22T11:53:45.492Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi
[task 2022-09-22T11:53:45.492Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi
[task 2022-09-22T11:53:45.493Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi
[task 2022-09-22T11:53:45.494Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi allowed 2 more times before opening first browser window - 
[task 2022-09-22T11:53:45.495Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi as many times as expected before opening first browser window - 
[task 2022-09-22T11:53:45.495Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi allowed 2 more times before opening first browser window - 
[task 2022-09-22T11:53:45.496Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /builds/worker/workspace/build/application/firefox/browser/features/pictureinpicture@mozilla.org.xpi as many times as expected before opening first browser window - 
[task 2022-09-22T11:53:45.497Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi allowed 2 more times before opening first browser window - 
[task 2022-09-22T11:53:45.498Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /builds/worker/workspace/build/application/firefox/browser/features/screenshots@mozilla.org.xpi as many times as expected before opening first browser window - 
[task 2022-09-22T11:53:45.498Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi allowed 2 more times before opening first browser window - 
[task 2022-09-22T11:53:45.499Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /builds/worker/workspace/build/application/firefox/browser/features/webcompat-reporter@mozilla.org.xpi as many times as expected before opening first browser window - 
[task 2022-09-22T11:53:45.521Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | stat on /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi allowed 2 more times before opening first browser window - 
[task 2022-09-22T11:53:45.525Z] 11:53:45     INFO - TEST-PASS | browser/base/content/test/performance/browser_startup_mainthreadio.js | close on /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi as many times as expected before opening first browser window - 
[task 2022-09-22T11:53:45.559Z] 11:53:45     INFO - known main thread IO paths during before first paint:
[task 2022-09-22T11:53:45.561Z] 11:53:45     INFO -   /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi - condition: true, stat: 1, close: 1, listedPath: XREAppFeat:formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.561Z] 11:53:45     INFO -   *ld.so.conf* - condition: true, read: 22, close: 11, listedPath: *ld.so.conf*
[task 2022-09-22T11:53:45.562Z] 11:53:45     INFO -   /tmp/tmpq6cfkgbj.mozrunner/d3d11layers.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:d3d11layers.guard
[task 2022-09-22T11:53:45.562Z] 11:53:45     INFO -   /tmp/tmpq6cfkgbj.mozrunner/glcontext.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:glcontext.guard
[task 2022-09-22T11:53:45.567Z] 11:53:45     INFO -   /tmp/tmpq6cfkgbj.mozrunner/wmfvpxvideo.guard - ignoreIfUnused: true, stat: 1, listedPath: ProfD:wmfvpxvideo.guard
[task 2022-09-22T11:53:45.567Z] 11:53:45     INFO - Buffered messages finished
[task 2022-09-22T11:53:45.568Z] 11:53:45     INFO - TEST-UNEXPECTED-FAIL | browser/base/content/test/performance/browser_startup_mainthreadio.js | unexpected close on /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi before first paint - 
[task 2022-09-22T11:53:45.570Z] 11:53:45     INFO - Stack trace:
[task 2022-09-22T11:53:45.570Z] 11:53:45     INFO -   0x7f7dd5d71a46
[task 2022-09-22T11:53:45.571Z] 11:53:45     INFO -   Task nsJARChannel::ContinueOpenLocalFile
[task 2022-09-22T11:53:45.572Z] 11:53:45     INFO -   XREMain::XRE_main
[task 2022-09-22T11:53:45.573Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/webcompat@mozilla.org.xpi
[task 2022-09-22T11:53:45.573Z] 11:53:45     INFO - (NSPRIOInterposer) stat - /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.575Z] 11:53:45     INFO - (NSPRIOInterposer) close - /builds/worker/workspace/build/application/firefox/browser/features/formautofill@mozilla.org.xpi
[task 2022-09-22T11:53:45.576Z] 11:53:45     INFO - (NSPRIOInterposer) read - /etc/ld.so.conf
[task 2022-09-22T11:53:45.576Z] 11:53:45     INFO - (NSPRIOInterposer) read - /etc/ld.so.conf.d/x86_64-linux-gnu.conf
[task 2022-09-22T11:53:45.577Z] 11:53:45     INFO - (NSPRIOInterposer) read - /etc/ld.so.conf.d/x86_64-linux-gnu.conf
[task 2022-09-22T11:53:45.578Z] 11:53:45     INFO - (NSPRIOInterposer) close - /etc/ld.so.conf.d/x86_64-linux-gnu.conf
[task 2022-09-22T11:53:45.578Z] 11:53:45     INFO - (NSPRIOInterposer) read - /etc/ld.so.conf.d/libc.conf
[task 2022-09-22T11:53:45.587Z] 11:53:45     INFO - (NSPRIOInterposer) read - /etc/ld.so.conf.d/libc.conf
[task 2022-09-22T11:53:45.587Z] 11:53:45     INFO - (NSPRIOInterposer) close - /etc/ld.so.conf.d/libc.conf

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

  • linux1804-64-qr
  • linux1804-64-shippable-qr
  • windows10-64-2004-ccov-qr
  • windows10-64-2004-qr
  • windows10-64-2004-shippable-qr
  • windows7-32-qr
  • windows7-32-shippable-qr

It looks like this is perma for a while now, Ben can you please help us with this, I think it might be caused by Bug 1782295 but it's a long shot, can you please take a look or redirect this to someone who might help?
Thanks!
Retriggers: https://treeherder.mozilla.org/jobs?repo=autoland&group_state=expanded&fromchange=aeb44b79bf31f0e61bfc42939f7d79308cf764a9&searchStr=windows%2C7%2Cwebrender%2Copt%2Cmochitests%2Ctest-windows7-32-qr%2Fopt-mochitest-browser-chrome%2Cbc5&tochange=9fb70b4ae59336b805a1651e7c57c6385cca0717&selectedTaskRun=fIG5goIUSOe3UIJmQz9arA.0

Flags: needinfo?(bhearsum)
Summary: Intermittent browser/base/content/test/performance/browser_startup_mainthreadio.js | single tracking bug → Perma browser/base/content/test/performance/browser_startup_mainthreadio.js | single tracking bug

After more investigations I got to the right regression, I hope. Backfills Paul can you please take a look at this?
Ben, sorry about the ping.

Flags: needinfo?(bhearsum) → needinfo?(pbz)
Regressed by: 1790100

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

I'll take a look. We might have to initialize the cookie banner handling components a bit later after startup.

Assignee: nobody → pbz
Status: NEW → ASSIGNED
Flags: needinfo?(pbz)
Pushed by pzuhlcke@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/de07e2b641d8
Defer CookiebannerListService init to prevent early main-thread IO. r=timhuang
Status: ASSIGNED → RESOLVED
Closed: 2 years ago
Resolution: --- → FIXED
Regressions: 1794551

Thanks Paul!

See Also: → 1794715
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: