Closed Bug 1805087 Opened 3 years ago Closed 10 months ago

Intermittent /resize-observer/callback-cross-realm-report-exception.html | single tracking bug

Categories

(Core :: Layout, defect, P5)

Product:
Core
Component:
Layout
Type:
defect

Tracking

()

Status:
RESOLVED INCOMPLETE
Tracking Flags:
Tracking Status
firefox-esr102 --- unaffected
firefox108 --- unaffected
firefox109 --- wontfix
firefox110 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

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

Filed by: csabou [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=399276035&repo=autoland
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/Y9WuXNFtQAW_6sjGFKWWJA/runs/0/artifacts/public/logs/live_backing.log

[task 2022-12-11T13:27:44.394Z] 13:27:44     INFO - TEST-OK | /resize-observer/calculate-depth-for-node.html | took 347ms
[task 2022-12-11T13:27:44.401Z] 13:27:44     INFO - TEST-START | /resize-observer/callback-cross-realm-report-exception.html
[task 2022-12-11T13:27:44.407Z] 13:27:44     INFO - Closing window 24a66f3a-3290-4f61-8799-41e4ff960cc3
[task 2022-12-11T13:27:44.726Z] 13:27:44     INFO - PID 5180 | [Parent 5052, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1164
[task 2022-12-11T13:27:44.742Z] 13:27:44     INFO - PID 5180 | [Parent 5052, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1164
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - 
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - TEST-UNEXPECTED-FAIL | /resize-observer/callback-cross-realm-report-exception.html | ResizeObserver reports the exception from its callback in the callback's global object - assert_array_equals: lengths differ, expected array ["frame1"] length 1, got [] length 0
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - window.onload</<@http://web-platform.test:8000/resize-observer/callback-cross-realm-report-exception.html:25:26
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - Test.prototype.step_timeout/<@http://web-platform.test:8000/resources/testharness.js:2711:25
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2591:25
[task 2022-12-11T13:27:44.895Z] 13:27:44     INFO - Test.prototype.step_func/<@http://web-platform.test:8000/resources/testharness.js:2638:35
[task 2022-12-11T13:27:44.904Z] 13:27:44     INFO - TEST-OK | /resize-observer/callback-cross-realm-report-exception.html | took 501ms
[task 2022-12-11T13:27:44.905Z] 13:27:44     INFO - PID 5180 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167075982303341\AppData\Local\Temp\tmpaitz9byp\runtests_leaks_4688_tab_pid5264.log
[task 2022-12-11T13:27:44.907Z] 13:27:44     INFO - PID 5180 | [5264, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-12-11T13:27:44.907Z] 13:27:44     INFO - PID 5180 | 1670765264903	Marionette	INFO	Stopped listening on port 56733
[task 2022-12-11T13:27:44.917Z] 13:27:44     INFO - PID 5180 | JavaScript error: http://web-platform.test:8000/resize-observer/callback-cross-realm-report-exception.html line 21 > Function, line 3: Error: PASS
Regressed by: 1802588

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

status-firefox108: --- → unaffected
status-firefox109: --- → affected
status-firefox110: --- → affected
status-firefox-esr102: --- → unaffected
Keywords: regression

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

  • linux1804-64-qr
  • windows10-32-2004-qr
  • windows10-32-2004-shippable-qr
  • windows10-64-2004-ccov-qr
  • windows10-64-2004-shippable-qr
[task 2022-12-23T11:53:52.882Z] 11:53:52     INFO - TEST-START | /resize-observer/callback-cross-realm-report-exception.html
[task 2022-12-23T11:53:52.891Z] 11:53:52     INFO - Closing window 535e828d-08ee-4d30-b9cd-90e2deb2f1fb
[task 2022-12-23T11:53:53.211Z] 11:53:53     INFO - PID 3932 | [Parent 1796, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1164
[task 2022-12-23T11:53:53.225Z] 11:53:53     INFO - PID 3932 | [Parent 1796, Main Thread] WARNING: NS_ENSURE_TRUE(she && she->mInfo->mSharedState.Get()) failed: file /builds/worker/checkouts/gecko/docshell/shistory/SessionHistoryEntry.cpp:1164
[task 2022-12-23T11:53:53.283Z] 11:53:53     INFO - PID 3932 | JavaScript error: http://web-platform.test:8000/resize-observer/callback-cross-realm-report-exception.html line 21 > Function, line 3: Error: PASS
[task 2022-12-23T11:53:53.358Z] 11:53:53     INFO - PID 3932 | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to C:\Users\task_167179106499641\AppData\Local\Temp\tmpca88z1cf\runtests_leaks_5612_tab_pid1996.log
[task 2022-12-23T11:53:53.370Z] 11:53:53     INFO - PID 3932 | [1996, Main Thread] WARNING: XPCOM_MEM_BLOAT_LOG is set, disabling native allocations.: file /builds/worker/checkouts/gecko/tools/profiler/core/platform.cpp:339
[task 2022-12-23T11:53:53.372Z] 11:53:53     INFO - 
[task 2022-12-23T11:53:53.372Z] 11:53:53     INFO - TEST-UNEXPECTED-PASS | /resize-observer/callback-cross-realm-report-exception.html | ResizeObserver reports the exception from its callback in the callback's global object - expected FAIL
[task 2022-12-23T11:53:53.372Z] 11:53:53     INFO - TEST-INFO | expected FAIL
[task 2022-12-23T11:53:53.379Z] 11:53:53     INFO - TEST-OK | /resize-observer/callback-cross-realm-report-exception.html | took 499ms
Flags: needinfo?(james)
Whiteboard: [stockwell needswork:owner]
Component: web-platform-tests → Layout
Product: Testing → Core

Daniel, any thoughts on comment 4? Would just an expectation update do here? This popped up after a wpt-sync.

Flags: needinfo?(james) → needinfo?(dholbert)
Summary: Intermittent /resize-observer/callback-cross-realm-report-exception.html | single tracking bug → High frequency /resize-observer/callback-cross-realm-report-exception.html | single tracking bug
Pushed by csabou@mozilla.com: https://hg.mozilla.org/integration/autoland/rev/525aa0e94693 Mark callback-cross-realm-report-exception.html subtest as intermittent on win 32 bits. a=test-only
Keywords: leave-open
Summary: High frequency /resize-observer/callback-cross-realm-report-exception.html | single tracking bug → Intermittent /resize-observer/callback-cross-realm-report-exception.html | single tracking bug

(In reply to Cosmin Sabou [:CosminS] from comment #5)

Daniel, any thoughts on comment 4? Would just an expectation update do here? This popped up after a wpt-sync.

Sorry for never replying to this - looks like we ended up updated the expectations, which makes sense. I guess this is marked as random on all platforms aside from mac debug at this point, per
https://searchfox.org/mozilla-central/rev/8c7e56f7bd827bd8c4016ee12a631604d691f597/testing/web-platform/meta/resize-observer/callback-cross-realm-report-exception.html.ini

...and we're getting intermittent failures there too, so we should probably just mark it as random everywhere i.e. expected: [PASS, FAIL]

This is likely just a race condition of some sort; not sure if it's in the test or our implementation, but realistically this won't get looked at any time soon until/unless it causes problems in the wild.

The test here came from another browser vendor, so we don't necessarily have confidence in its correctness or that it's bug-free; and it uses step_timeout with a hardcoded 25ms timeout value:
https://searchfox.org/mozilla-central/rev/8c7e56f7bd827bd8c4016ee12a631604d691f597/testing/web-platform/tests/resize-observer/callback-cross-realm-report-exception.html#24-27

t.step_timeout(() => {
  assert_array_equals(onerrorCalls, ["frame1"]);
  t.done();
}, 25);

...which is a sign that it might be flaky. This could easily be that there's some operation that usually completes within 25ms that the test is waiting for there, and if that operation happens to take longer for random reasons, then the test spuriously fails.

Flags: needinfo?(dholbert)

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup
For more information, please visit BugBot documentation.

Status: NEW → RESOLVED
Closed: 10 months ago
status-firefox110: fix-optional → wontfix
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.