1787787 - Intermittent layout/base/crashtests/1747277-1.html | load failed: timed out waiting for reftest-wait to be removed

Status: RESOLVED FIXED

(Core :: Layout, defect, P5)

Description

[Treeherder Bug Filer]

Filed by: ccozmuta [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer?job_id=388773472&repo=try
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/LqalVYg4SdyaJQ6gbrG1oA/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/LqalVYg4SdyaJQ6gbrG1oA/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

---

[task 2022-08-29T13:46:34.462Z] 13:46:34     INFO - REFTEST TEST-START | layout/base/crashtests/1747277-1.html
[task 2022-08-29T13:46:34.463Z] 13:46:34     INFO - REFTEST INFO | RESTORE PREFERENCE pref(layout.accessiblecaret.enabled,false)
[task 2022-08-29T13:46:34.463Z] 13:46:34     INFO - REFTEST TEST-LOAD | file:///opt/worker/tasks/task_166178031366798/build/tests/reftest/tests/layout/base/crashtests/1747277-1.html | 2056 / 3980 (51%)
[task 2022-08-29T13:46:34.687Z] 13:46:34     INFO - 1661780794686	Marionette	TRACE	Received observer notification browser-delayed-startup-finished
[task 2022-08-29T13:51:34.465Z] 13:51:34     INFO - REFTEST TEST-UNEXPECTED-FAIL | layout/base/crashtests/1747277-1.html | load failed: timed out waiting for reftest-wait to be removed
[task 2022-08-29T13:51:34.466Z] 13:51:34     INFO - REFTEST INFO | Saved log: START file:///opt/worker/tasks/task_166178031366798/build/tests/reftest/tests/layout/base/crashtests/1747277-1.html
[task 2022-08-29T13:51:34.466Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
[task 2022-08-29T13:51:34.467Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
[task 2022-08-29T13:51:34.467Z] 13:51:34     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2022-08-29T13:51:34.467Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2022-08-29T13:51:34.468Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2022-08-29T13:51:34.468Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
[task 2022-08-29T13:51:34.469Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2022-08-29T13:51:34.470Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:video/webm;base64,GkXfowEAAAAAAAAfQoaBAUL3gQFC8oEEQvOBCEKChHdlYm1Ch4ECQoWBAhhTgGcBAAAAAAAB6BFNm3RALE27i1OrhBVJqWZTrIHfTbuMU6uEFlSua1OsggEwTbuMU6uEHFO7a1OsggHL7AEAAAAAAACkAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAVSalmAQAAAAAAAEUq17GDD0JATYCNTGF2ZjU3LjI5LjEwMVdBjUxhdmY1Ny4yOS4xMDFzpJBAb17Yv2oNAF1ZEESuco33RImIQFCAAAAAAAAWVK5rAQAAAAAAADyuAQAAAAAAADPXgQFzxYEBnIEAIrWcg3VuZIaFVl9WUDmDgQEj44OEAfygVeABAAAAAAAAB7CCAUC6gfAfQ7Z1AQAAAAAAAEfngQCjqYEAAICCSYNCABPwDvYAOCQcGFQAAFBh9jAAABML7AAATEnjdRwIJ+gAo5eBACEAhgBAkpwATEAABCasAABekcXgABxTu2sBAAAAAAAAEbuPs4EAt4r3gQHxggF48IED
[task 2022-08-29T13:51:34.471Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2022-08-29T13:51:34.471Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
[task 2022-08-29T13:51:34.471Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] HandlePendingTasksAfterMakeProgress updating canvas
[task 2022-08-29T13:51:34.472Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] Sending update whole canvas for invalidation
[task 2022-08-29T13:51:34.472Z] 13:51:34     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
[task 2022-08-29T13:51:34.473Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2022-08-29T13:51:34.474Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:video/webm;base64,GkXfowEAAAAAAAAfQoaBAUL3gQFC8oEEQvOBCEKChHdlYm1Ch4ECQoWBAhhTgGcBAAAAAAAB6BFNm3RALE27i1OrhBVJqWZTrIHfTbuMU6uEFlSua1OsggEwTbuMU6uEHFO7a1OsggHL7AEAAAAAAACkAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAVSalmAQAAAAAAAEUq17GDD0JATYCNTGF2ZjU3LjI5LjEwMVdBjUxhdmY1Ny4yOS4xMDFzpJBAb17Yv2oNAF1ZEESuco33RImIQFCAAAAAAAAWVK5rAQAAAAAAADyuAQAAAAAAADPXgQFzxYEBnIEAIrWcg3VuZIaFVl9WUDmDgQEj44OEAfygVeABAAAAAAAAB7CCAUC6gfAfQ7Z1AQAAAAAAAEfngQCjqYEAAICCSYNCABPwDvYAOCQcGFQAAFBh9jAAABML7AAATEnjdRwIJ+gAo5eBACEAhgBAkpwATEAABCasAABekcXgABxTu2sBAAAAAAAAEbuPs4EAt4r3gQHxggF48IED
[task 2022-08-29T13:51:34.474Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2022-08-29T13:51:34.475Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
[task 2022-08-29T13:51:34.475Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] HandlePendingTasksAfterMakeProgress updating canvas
[task 2022-08-29T13:51:34.476Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] Sending update whole canvas for invalidation
[task 2022-08-29T13:51:34.476Z] 13:51:34     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
<...>
[task 2022-08-29T13:51:34.520Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] FromChildAfterPaintListener from data:video/webm;base64,GkXfowEAAAAAAAAfQoaBAUL3gQFC8oEEQvOBCEKChHdlYm1Ch4ECQoWBAhhTgGcBAAAAAAAB6BFNm3RALE27i1OrhBVJqWZTrIHfTbuMU6uEFlSua1OsggEwTbuMU6uEHFO7a1OsggHL7AEAAAAAAACkAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAVSalmAQAAAAAAAEUq17GDD0JATYCNTGF2ZjU3LjI5LjEwMVdBjUxhdmY1Ny4yOS4xMDFzpJBAb17Yv2oNAF1ZEESuco33RImIQFCAAAAAAAAWVK5rAQAAAAAAADyuAQAAAAAAADPXgQFzxYEBnIEAIrWcg3VuZIaFVl9WUDmDgQEj44OEAfygVeABAAAAAAAAB7CCAUC6gfAfQ7Z1AQAAAAAAAEfngQCjqYEAAICCSYNCABPwDvYAOCQcGFQAAFBh9jAAABML7AAATEnjdRwIJ+gAo5eBACEAhgBAkpwATEAABCasAABekcXgABxTu2sBAAAAAAAAEbuPs4EAt4r3gQHxggF48IED
[task 2022-08-29T13:51:34.520Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] HandlePendingTasksAfterMakeProgress updating canvas
[task 2022-08-29T13:51:34.521Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] Sending update whole canvas for invalidation
[task 2022-08-29T13:51:34.521Z] 13:51:34     INFO - REFTEST INFO | Saved log: Updating entire canvas for invalidation
[task 2022-08-29T13:51:34.522Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2022-08-29T13:51:34.522Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2022-08-29T13:51:34.522Z] 13:51:34     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
[task 2022-08-29T13:51:34.523Z] 13:51:34     INFO - REFTEST TEST-END | layout/base/crashtests/1747277-1.html
[task 2022-08-29T13:51:34.523Z] 13:51:34     INFO - REFTEST TEST-START | layout/base/crashtests/1752649.html
[task 2022-08-29T13:51:34.524Z] 13:51:34     INFO - REFTEST TEST-LOAD | file:///opt/worker/tasks/task_166178031366798/build/tests/reftest/tests/layout/base/crashtests/1752649.html | 2057 / 3980 (51%)
[task 2022-08-29T13:51:34.654Z] 13:51:34     INFO - REFTEST TEST-PASS | layout/base/crashtests/1752649.html | (LOAD ONLY)

Comment 1

[Natalia Csoregi [:nataliaCs]]

Retriggers and backfills range: https://treeherder.mozilla.org/jobs?repo=mozilla-central&searchStr=OS%2CX%2C10.15%2CWebRender%2CShippable%2Copt%2CReftests%2Ctest-macosx1015-64-shippable-qr%2Fopt-crashtest%2CC&tochange=dfe1912f7a46889d7bfa088b8c2d3fa59930f8f0&fromchange=a8b2c7cf7d826e8289fe621aa39ebe4818caba08&group_state=expanded&selectedTaskRun=b6sitBBZRKKpH3FLL6LmOQ.0

Could Bug 1747277 be the cause?

Comment 2

[Timothy Nikkel (:tnikkel)]

Yes. Bug 1747277 added the failing test.

Comment 3

[BugBot [:suhaib / :marco/ :calixte]]

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

Comment 4

[Daniel Holbert [:dholbert]]

Looks like failures have only been reported on macos opt so far. Maybe worth extending the skip-if annotation to include macOS for now, to stem the apparent high volume of failures here?

Comment 5

[Intermittent Failures Robot]

31 failures in 3537 pushes (0.009 failures/push) were associated with this bug in the last 7 days.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• try: 6
• mozilla-central: 13
• autoland: 12

Platform and build breakdown:

• macosx1015-64-devedition-qr: 3
  • opt: 3
• macosx1015-64-shippable-qr: 16
  • opt: 16
• macosx1015-64-qr: 12
  • opt: 12

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1787787&startday=2022-08-29&endday=2022-09-04&tree=all

Comment 6

[Intermittent Failures Robot]

35 failures in 3616 pushes (0.01 failures/push) were associated with this bug in the last 7 days.

This is the #33 most frequent failure this week.

** This failure happened more than 30 times this week! Resolving this bug is a high priority. **

** Try to resolve this bug as soon as possible. If unresolved for 2 weeks, the affected test(s) may be disabled. **

Repository breakdown:

• autoland: 16
• try: 10
• mozilla-central: 9

Platform and build breakdown:

• macosx1015-64-qr: 16
  • opt: 16
• macosx1015-64-devedition-qr: 6
  • opt: 6
• macosx1015-64-shippable-qr: 13
  • opt: 13

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1787787&startday=2022-09-05&endday=2022-09-11&tree=all

Comment 7

[Timothy Nikkel (:tnikkel)]

Attachment: Bug 1787787. Fix intermittent in layout/base/crashtests/1747277-1.html. r?dholbert

Comment 8

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/95824b45a6c6
Fix intermittent in layout/base/crashtests/1747277-1.html. r=emilio

Comment 9

[Norisz Fay [:noriszfay]]

https://hg.mozilla.org/mozilla-central/rev/95824b45a6c6

Comment 10

[Intermittent Failures Robot]

5 failures in 3023 pushes (0.002 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• mozilla-central: 2
• autoland: 2
• try: 1

Platform and build breakdown:

• macosx1015-64-shippable-qr: 2
  • opt: 2
• macosx1015-64-qr: 2
  • opt: 2
• macosx1015-64-devedition-qr: 1
  • opt: 1

For more details, see:
https://treeherder.mozilla.org/intermittent-failures/bugdetails?bug=1787787&startday=2022-09-12&endday=2022-09-18&tree=all