Closed Bug 1789893 Opened 2 years ago Closed 4 months ago

Intermittent /css/css-sizing/contain-intrinsic-size/auto-008.html | single tracking bug

Categories

(Testing :: web-platform-tests, defect, P5)

defect

Tracking

(firefox-esr91 unaffected, firefox-esr102 unaffected, firefox104 unaffected, firefox105 unaffected, firefox106 wontfix, firefox107 wontfix)

RESOLVED INCOMPLETE
Tracking Status
firefox-esr91 --- unaffected
firefox-esr102 --- unaffected
firefox104 --- unaffected
firefox105 --- unaffected
firefox106 --- wontfix
firefox107 --- wontfix

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Regression)

Details

(Keywords: intermittent-failure, intermittent-testcase, regression)

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


[task 2022-09-08T17:14:10.200Z] 17:14:10     INFO - TEST-START | /css/css-sizing/contain-intrinsic-size/auto-008.html
[task 2022-09-08T17:14:10.201Z] 17:14:10     INFO - Run 8/10
[task 2022-09-08T17:14:10.205Z] 17:14:10     INFO - Closing window 78417d76-6c3a-4a12-a051-541ebe1110c2
[task 2022-09-08T17:14:10.317Z] 17:14:10     INFO - 
[task 2022-09-08T17:14:10.317Z] 17:14:10     INFO - TEST-UNEXPECTED-FAIL | /css/css-sizing/contain-intrinsic-size/auto-008.html | requestAnimationFrame - assert_equals: Step 1 expected 1 but got 3
[task 2022-09-08T17:14:10.317Z] 17:14:10     INFO - step1@http://web-platform.test:8000/css/css-sizing/contain-intrinsic-size/auto-008.html:54:16
[task 2022-09-08T17:14:10.317Z] 17:14:10     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-09-08T17:14:10.317Z] 17:14:10     INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2665:32
[task 2022-09-08T17:14:10.326Z] 17:14:10     INFO - 
[task 2022-09-08T17:14:10.326Z] 17:14:10     INFO - TEST-UNEXPECTED-FAIL | /css/css-sizing/contain-intrinsic-size/auto-008.html | Early ResizeObserver - assert_equals: Step 2 expected 2 but got 1
[task 2022-09-08T17:14:10.326Z] 17:14:10     INFO - step2@http://web-platform.test:8000/css/css-sizing/contain-intrinsic-size/auto-008.html:66:16
[task 2022-09-08T17:14:10.326Z] 17:14:10     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-09-08T17:14:10.326Z] 17:14:10     INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2665:32
[task 2022-09-08T17:14:10.329Z] 17:14:10     INFO - 
[task 2022-09-08T17:14:10.330Z] 17:14:10     INFO - TEST-UNEXPECTED-FAIL | /css/css-sizing/contain-intrinsic-size/auto-008.html | Late ResizeObserver - assert_equals: Step 3 expected 3 but got 2
[task 2022-09-08T17:14:10.330Z] 17:14:10     INFO - step3@http://web-platform.test:8000/css/css-sizing/contain-intrinsic-size/auto-008.html:83:16
[task 2022-09-08T17:14:10.330Z] 17:14:10     INFO - Test.prototype.step@http://web-platform.test:8000/resources/testharness.js:2590:25
[task 2022-09-08T17:14:10.330Z] 17:14:10     INFO - Test.prototype.step_func_done/<@http://web-platform.test:8000/resources/testharness.js:2665:32
[task 2022-09-08T17:14:10.330Z] 17:14:10     INFO - TEST-OK | /css/css-sizing/contain-intrinsic-size/auto-008.html | took 145ms
[task 2022-09-08T17:14:10.332Z] 17:14:10     INFO - PID 4944 | 1662657250330	Marionette	INFO	Stopped listening on port 49927
[task 2022-09-08T17:14:10.341Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=199e9000 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.342Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=199e9000 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.343Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1b5f0d00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.344Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1b5f0d00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.345Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862d00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.346Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862d00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.347Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.348Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.348Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.351Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.352Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - BlockShutdown: NotifyImpendingShutdown.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3627
[task 2022-09-08T17:14:10.353Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - BlockShutdown: RequestContentJSInterrupt.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3633
[task 2022-09-08T17:14:10.448Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3672
[task 2022-09-08T17:14:10.460Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-09-08T17:14:10.460Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3672
[task 2022-09-08T17:14:10.462Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-09-08T17:14:10.463Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - BlockShutdown: CanSend.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3672
[task 2022-09-08T17:14:10.464Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - ShutDownProcess: Sent shutdown message.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1804
[task 2022-09-08T17:14:10.465Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1844
[task 2022-09-08T17:14:10.466Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c862700 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3787
[task 2022-09-08T17:14:10.466Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1844
[task 2022-09-08T17:14:10.467Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=1c861500 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3787
[task 2022-09-08T17:14:10.469Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - ShutDownProcess: Closing channel.: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:1844
[task 2022-09-08T17:14:10.470Z] 17:14:10     INFO - PID 4944 | [Parent 3956, Main Thread] WARNING: ContentParent: id=2997be00 - RemoveShutdownBlockers: file /builds/worker/checkouts/gecko/dom/ipc/ContentParent.cpp:3787
[task 2022-09-08T17:14:11.162Z] 17:14:11     INFO - Browser exited with return code 0
[task 2022-09-08T17:14:11.167Z] 17:14:11     INFO - Closing logging queue
[task 2022-09-08T17:14:11.168Z] 17:14:11     INFO - queue closed
[task 2022-09-08T17:14:11.210Z] 17:14:11     INFO - Application command: Z:\task_166264876019185\build\application\firefox\firefox.exe -marionette about:blank --wait-for-browser -profile C:\Users\task_166264876019185\AppData\Local\Temp\tmp1w49vd40
[task 2022-09-08T17:14:11.223Z] 17:14:11     INFO - Starting runner
[task 2022-09-08T17:14:11.895Z] 17:14:11     INFO - PID 5716 | 1662657251896	Marionette	INFO	Marionette enabled
[task 2022-09-08T17:14:11.900Z] 17:14:11     INFO - PID 5716 | 1662657251899	Marionette	INFO	Listening on port 49947
[task 2022-09-08T17:14:13.489Z] 17:14:13     INFO - PID 5716 | console.warn: SearchSettings: "get: No settings file exists, new profile?" (new NotFoundError("Could not open the file at C:\\Users\\task_166264876019185\\AppData\\Local\\Temp\\tmp1w49vd40\\search.json.mozlz4", (void 0)))
[task 2022-09-08T17:14:13.491Z] 17:14:13     INFO - PID 5716 | *** You are running in background task mode. ***
[task 2022-09-08T17:14:13.492Z] 17:14:13     INFO - PID 5716 | *** You are running in headless mode.
[task 2022-09-08T17:14:13.589Z] 17:14:13     INFO - PID 5716 | console.error: "C:\\Users\\task_166264876019185\\AppData\\Local\\Temp\\tmp1w49vd40" "" 0 ".purge.bg_rm"
[task 2022-09-08T17:14:14.354Z] 17:14:14     INFO - TEST-START | /css/css-sizing/contain-intrinsic-size/auto-008.html
Regressed by: 1789855
Summary: Intermittent TVw /css/css-sizing/contain-intrinsic-size/auto-008.html | requestAnimationFrame - assert_equals: Step 1 expected 1 but got 3 → Intermittent /css/css-sizing/contain-intrinsic-size/auto-008.html | requestAnimationFrame - assert_equals: Step 1 expected 1 but got 3

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

:Oriol, since you are the author of the regressor, bug 1789855, could you take a look?

For more information, please visit auto_nag documentation.

Seems requestAnimationFrame callbacks are sometimes invoked after ResizeObserver callbacks? AFAIK that's wrong according to the spec:

Emilio, do you have any idea of why this may sometimes result in step2 being called before step1?

requestAnimationFrame(test1.step_func_done(step1));
new ResizeObserver(test2.step_func_done(step2)).observe(target2);
Flags: needinfo?(oriol-bugzilla) → needinfo?(emilio)

ResizeObserver is triggered from:

Which is called from:

While rAF is triggered from:

So I don't think Gecko right now is guaranteeing that ordering... Seems worth fixing tho. In general moving our refresh driver to match "update the rendering" more closely would be great. Will file a bug to track this properly.

Flags: needinfo?(emilio)
No longer depends on: 1790131

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

Summary: Intermittent /css/css-sizing/contain-intrinsic-size/auto-008.html | requestAnimationFrame - assert_equals: Step 1 expected 1 but got 3 → Intermittent /css/css-sizing/contain-intrinsic-size/auto-008.html | single tracking bug
Status: NEW → RESOLVED
Closed: 1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago1 year ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 1 year ago7 months ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 7 months ago4 months ago
Resolution: --- → INCOMPLETE
You need to log in before you can comment on or make changes to this bug.