Closed Bug 2033730 Opened 2 months ago Closed 2 months ago

Intermittent TV accessible/tests/browser/e10s/browser_caching_attributes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. Test ran fo

Categories

(Core :: Disability Access APIs, defect, P5)

Product:
Core
Component:
Disability Access APIs
Type:
defect

Tracking

()

Status:
RESOLVED FIXED
Milestone:
152 Branch
Tracking Flags:
Tracking Status
firefox-esr140 --- unaffected
firefox150 --- unaffected
firefox151 --- unaffected
firefox152 --- fixed

People

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

References

(Regression)

Details

(Keywords: intermittent-failure, regression, test-verify-fail)

Attachments

(1 file)

Bug 2033730 - Add requestLongerTimeout to browser_caching_attributes.js to avoid intermittents in slower builds and environments r=Jamie
48 bytes, text/x-phabricator-request
Details | Review

Filed by: rperta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=561383528&repo=autoland&task=OOIO8cv_S-OWRPcFTCvqmA.0
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OOIO8cv_S-OWRPcFTCvqmA/runs/0/artifacts/public/logs/live_backing.log
Reftest URL: https://hg.mozilla.org/mozilla-central/raw-file/default/layout/tools/reftest/reftest-analyzer.xhtml#logurl=https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/OOIO8cv_S-OWRPcFTCvqmA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

[task 2026-04-21T10:09:29.942+00:00] 10:09:29     INFO - Console message: [JavaScript Warning: "Empty string passed to getElementById()." {file: "chrome://browser/content/browser.xhtml" line: 0}]
[task 2026-04-21T10:09:29.943+00:00] 10:09:29     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_attributes.js | testModalDialogNoAttr_chrome - Not remote browser
[task 2026-04-21T10:09:29.943+00:00] 10:09:29     INFO - Buffered messages logged at 10:09:28
[task 2026-04-21T10:09:29.943+00:00] 10:09:29     INFO - Showing modal dialog
[task 2026-04-21T10:09:29.944+00:00] 10:09:29     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_attributes.js | testModalDialogNoAttr_chrome - Received show event
[task 2026-04-21T10:09:29.945+00:00] 10:09:29     INFO - TEST-PASS | accessible/tests/browser/e10s/browser_caching_attributes.js | testModalDialogNoAttr_chrome -  for [DOM node id: modal_dialog, role: dialog, address: [xpconnect wrapped nsIAccessible]]
[task 2026-04-21T10:09:29.945+00:00] 10:09:29     INFO - Leaving test testModalDialogNoAttr_chrome
[task 2026-04-21T10:09:29.946+00:00] 10:09:29     INFO - Console message: [JavaScript Warning: "Empty string passed to getElementById()." {file: "chrome://browser/content/browser.xhtml" line: 0}]
[task 2026-04-21T10:09:29.946+00:00] 10:09:29     INFO - Buffered messages finished
[task 2026-04-21T10:09:29.947+00:00] 10:09:29     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/e10s/browser_caching_attributes.js | This test exceeded the timeout threshold. It should be rewritten or split up. If that's not possible, use requestLongerTimeout(N), but only as a last resort. Test ran for 97s, limit was 90s (timeout factor 1).
[task 2026-04-21T10:09:29.947+00:00] 10:09:29     INFO - GECKO(1782) | MEMORY STAT vsizeMaxContiguous not supported in this build configuration.
[task 2026-04-21T10:09:29.948+00:00] 10:09:29     INFO - GECKO(1782) | MEMORY STAT | vsize 37401MB | residentFast 343MB | heapAllocated 122MB
[task 2026-04-21T10:09:29.948+00:00] 10:09:29     INFO - checking for open sidebars
[task 2026-04-21T10:09:29.949+00:00] 10:09:29     INFO - checking window state
[task 2026-04-21T10:09:29.949+00:00] 10:09:29     INFO - Not taking screenshot here: see the one that was previously logged
[task 2026-04-21T10:09:29.950+00:00] 10:09:29     INFO - TEST-UNEXPECTED-FAIL | accessible/tests/browser/e10s/browser_caching_attributes.js | finished in 96324ms
[task 2026-04-21T10:09:29.950+00:00] 10:09:29     INFO - TEST-INFO took 96343ms
[task 2026-04-21T10:09:29.951+00:00] 10:09:29     INFO - GECKO(1782) | Completed ShutdownLeaks collections in process 1782
[task 2026-04-21T10:09:29.951+00:00] 10:09:29     INFO - TEST-START | Shutdown
[task 2026-04-21T10:09:29.952+00:00] 10:09:29     INFO - Browser Chrome Test Summary
[task 2026-04-21T10:09:29.952+00:00] 10:09:29     INFO - Passed:  467
[task 2026-04-21T10:09:29.952+00:00] 10:09:29     INFO - Failed:  1
[task 2026-04-21T10:09:29.953+00:00] 10:09:29     INFO - Todo:    0
[task 2026-04-21T10:09:29.953+00:00] 10:09:29     INFO - Mode:    e10s
[task 2026-04-21T10:09:29.954+00:00] 10:09:29     INFO - *** End BrowserChrome Test Results ***
[task 2026-04-21T10:09:29.954+00:00] 10:09:29     INFO - GECKO(1782) | Exiting due to channel error.
[task 2026-04-21T10:09:29.955+00:00] 10:09:29     INFO - GECKO(1782) | Exiting due to channel error.
[task 2026-04-21T10:09:29.955+00:00] 10:09:29     INFO - GECKO(1782) | Exiting due to channel error.
[task 2026-04-21T10:09:29.955+00:00] 10:09:29     INFO - GECKO(1782) | Exiting due to channel error.
[task 2026-04-21T10:09:29.956+00:00] 10:09:29     INFO - TEST-INFO | Main app process: exit 0
[task 2026-04-21T10:09:29.956+00:00] 10:09:29     INFO - runtests.py | Application ran for: 0:02:05.966191
[task 2026-04-21T10:09:29.956+00:00] 10:09:29     INFO - zombiecheck | Reading PID log: /var/folders/_b/v98wvr415j53vllyt1k0v0rc0000jb/T/tmpgy_rhoe6pidlog
[task 2026-04-21T10:09:29.957+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1785
[task 2026-04-21T10:09:29.957+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1786
[task 2026-04-21T10:09:29.958+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1787
[task 2026-04-21T10:09:29.958+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1793
[task 2026-04-21T10:09:29.958+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1801
[task 2026-04-21T10:09:29.959+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1802
[task 2026-04-21T10:09:29.959+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1803
[task 2026-04-21T10:09:29.959+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1804
[task 2026-04-21T10:09:29.960+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1805
[task 2026-04-21T10:09:29.960+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1829
[task 2026-04-21T10:09:29.960+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1835
[task 2026-04-21T10:09:29.961+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1838
[task 2026-04-21T10:09:29.961+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1839
[task 2026-04-21T10:09:29.961+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1851
[task 2026-04-21T10:09:29.961+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1857
[task 2026-04-21T10:09:29.962+00:00] 10:09:29     INFO - ==> process 1782 launched child process 1870
[task 2026-04-21T10:09:29.962+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1793
[task 2026-04-21T10:09:29.962+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1851
[task 2026-04-21T10:09:29.963+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1857
[task 2026-04-21T10:09:29.963+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1829
[task 2026-04-21T10:09:29.964+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1801
[task 2026-04-21T10:09:29.964+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1802
[task 2026-04-21T10:09:29.964+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1803
[task 2026-04-21T10:09:29.964+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1804
[task 2026-04-21T10:09:29.965+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1805
[task 2026-04-21T10:09:29.965+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1835
[task 2026-04-21T10:09:29.966+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1838
[task 2026-04-21T10:09:29.966+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1839
[task 2026-04-21T10:09:29.967+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1870
[task 2026-04-21T10:09:29.967+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1785
[task 2026-04-21T10:09:29.967+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1786
[task 2026-04-21T10:09:29.968+00:00] 10:09:29     INFO - zombiecheck | Checking for orphan process with PID: 1787
[task 2026-04-21T10:09:29.968+00:00] 10:09:29     INFO - runtests.py | Running http tests: end. status: 0
[task 2026-04-21T10:09:29.968+00:00] 10:09:29     INFO - Stopping web server
[task 2026-04-21T10:09:29.969+00:00] 10:09:29     INFO - Server shut down.
[task 2026-04-21T10:09:29.969+00:00] 10:09:29     INFO - Web server killed.
[task 2026-04-21T10:09:29.969+00:00] 10:09:29     INFO - Stopping web socket server
[task 2026-04-21T10:09:29.970+00:00] 10:09:29     INFO - Stopping ssltunnel
[task 2026-04-21T10:09:29.970+00:00] 10:09:29  WARNING - leakcheck | refcount logging is off, so leaks can't be detected!
[task 2026-04-21T10:09:29.970+00:00] 10:09:29     INFO - Buffered messages finished
[task 2026-04-21T10:09:29.971+00:00] 10:09:29     INFO -  TEST-INFO | checking window state
[task 2026-04-21T10:09:29.971+00:00] 10:09:29     INFO -  Browser Chrome Test Summary
[task 2026-04-21T10:09:29.971+00:00] 10:09:29     INFO -  	Passed: 7472
[task 2026-04-21T10:09:29.971+00:00] 10:09:29     INFO -  	Failed: 1
[task 2026-04-21T10:09:29.971+00:00] 10:09:29     INFO -  	Todo: 0
[task 2026-04-21T10:09:29.972+00:00] 10:09:29     INFO -  	Mode: e10s
[task 2026-04-21T10:09:29.972+00:00] 10:09:29     INFO -  *** End BrowserChrome Test Results ***
[task 2026-04-21T10:09:29.972+00:00] 10:09:29     INFO - Buffered messages finished
[task 2026-04-21T10:09:29.972+00:00] 10:09:29     INFO - SUITE-END | took 126s
[task 2026-04-21T10:09:29.973+00:00] 10:09:29     INFO - SUITE-START | Running 1 tests
[task 2026-04-21T10:09:29.973+00:00] 10:09:29     INFO - :::
[task 2026-04-21T10:09:29.973+00:00] 10:09:29     INFO - ::: Test verification summary for:
[task 2026-04-21T10:09:29.974+00:00] 10:09:29     INFO - :::
[task 2026-04-21T10:09:29.974+00:00] 10:09:29     INFO - ::: accessible/tests/browser/e10s/browser_caching_attributes.js
[task 2026-04-21T10:09:29.974+00:00] 10:09:29     INFO - :::
[task 2026-04-21T10:09:29.975+00:00] 10:09:29     INFO - ::: 1. Run each test 10 times in one browser. : Pass
[task 2026-04-21T10:09:29.975+00:00] 10:09:29     INFO - ::: 2. Run each test 5 times in a new browser each time. : Pass
[task 2026-04-21T10:09:29.976+00:00] 10:09:29     INFO - ::: 3. Run each test 10 times in one browser, in chaos mode. : FAIL
[task 2026-04-21T10:09:29.976+00:00] 10:09:29     INFO - ::: 4. Run each test 5 times in a new browser each time, in chaos mode. : not run / incomplete
[task 2026-04-21T10:09:29.976+00:00] 10:09:29     INFO - :::
[task 2026-04-21T10:09:29.977+00:00] 10:09:29     INFO - ::: Test verification FAILED!
[task 2026-04-21T10:09:29.977+00:00] 10:09:29     INFO - :::
[task 2026-04-21T10:09:29.977+00:00] 10:09:29     INFO - Buffered messages finished
[task 2026-04-21T10:09:29.978+00:00] 10:09:29     INFO - SUITE-END | took 0s
[task 2026-04-21T10:09:29.978+00:00] 10:09:29     INFO - Return code: 0
[task 2026-04-21T10:09:29.978+00:00] 10:09:29  WARNING - Got 2 unexpected statuses
[task 2026-04-21T10:09:29.978+00:00] 10:09:29     INFO - TinderboxPrint: mochitest-mochitest-browser-a11y<br/>482/<em class="testfail">2</em>/0
[task 2026-04-21T10:09:29.978+00:00] 10:09:29  WARNING - setting return code to 1
Keywords: regression
Regressed by: 2031407

:julienw, since you are the author of the regressor, bug 2031407, could you take a look?

For more information, please visit BugBot documentation.

Flags: needinfo?(felash)

The test doesn't look like to fail but it seems to run slow, because it has a lot of tests. Adding requestLongerTimeout doesn't seem a bad idea?

Flags: needinfo?(felash)
Assignee: nobody → felash
Status: NEW → ASSIGNED

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

status-firefox150: --- → unaffected
status-firefox151: --- → unaffected
status-firefox152: --- → affected
status-firefox-esr140: --- → unaffected
Pushed by jwajsberg@mozilla.com: https://github.com/mozilla-firefox/firefox/commit/012681755f64 https://hg.mozilla.org/integration/autoland/rev/021e48357a1c Add requestLongerTimeout to browser_caching_attributes.js to avoid intermittents in slower builds and environments r=Jamie

https://hg.mozilla.org/mozilla-central/rev/021e48357a1c

Status: ASSIGNED → RESOLVED
Closed: 2 months ago
status-firefox152: affected → fixed
Resolution: --- → FIXED
Target Milestone: --- → 152 Branch
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Creator:
Created:
Updated:
Size: