Closed Bug 1592059 Opened 5 years ago Closed 5 years ago

ubuntu1804 - image/test/reftest/downscaling/downscale-moz-icon-1.html == image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | load failed: timed out waiting for reftest-wait to be removed

Categories

(Core :: Graphics: ImageLib, defect, P5)

Product:
Core
Component:
Graphics: ImageLib
Type:
defect

Tracking

()

Status:
RESOLVED FIXED

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

Filed by: egao [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=271937755&repo=try
Full log: https://queue.taskcluster.net/v1/task/aY1k6kDNT9ShzHqXSqG_YQ/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://queue.taskcluster.net/v1/task/aY1k6kDNT9ShzHqXSqG_YQ/runs/0/artifacts/public/logs/live_backing.log&only_show_unexpected=1

suite: reftest-5

context:
[task 2019-10-18T17:30:22.188Z] 17:30:22 INFO - REFTEST TEST-START | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-10-18T17:30:22.189Z] 17:30:22 INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html | 47 / 136 (34%)
[task 2019-10-18T17:30:22.257Z] 17:30:22 INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | 47 / 136 (34%)
[task 2019-10-18T17:35:22.262Z] 17:35:22 INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | load failed: timed out waiting for reftest-wait to be removed
[task 2019-10-18T17:35:22.263Z] 17:35:22 INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html
[task 2019-10-18T17:35:22.264Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering AfterOnLoadScripts
[task 2019-10-18T17:35:22.264Z] 17:35:22 INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2019-10-18T17:35:22.265Z] 17:35:22 INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-10-18T17:35:22.265Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] RecordResult fired
[task 2019-10-18T17:35:22.265Z] 17:35:22 INFO - REFTEST INFO | Saved log: RecordResult fired
[task 2019-10-18T17:35:22.266Z] 17:35:22 INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-10-18T17:35:22.266Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
[task 2019-10-18T17:35:22.267Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
[task 2019-10-18T17:35:22.268Z] 17:35:22 INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2019-10-18T17:35:22.269Z] 17:35:22 INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-10-18T17:35:22.271Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2019-10-18T17:35:22.272Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
[task 2019-10-18T17:35:22.273Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-10-18T17:35:22.281Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
[task 2019-10-18T17:35:22.282Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 0 0 800 1000
[task 2019-10-18T17:35:22.282Z] 17:35:22 INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
[task 2019-10-18T17:35:22.283Z] 17:35:22 INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-10-18T17:35:22.284Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2019-10-18T17:35:22.287Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
[task 2019-10-18T17:35:22.288Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2019-10-18T17:35:22.289Z] 17:35:22 INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
[task 2019-10-18T17:35:22.290Z] 17:35:22 INFO - REFTEST TEST-END | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html

result also observed on ubuntu1804 running GNOME3 shell:

[task 2019-11-22T19:45:37.635Z] 19:45:37     INFO - REFTEST TEST-START | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-11-22T19:45:37.635Z] 19:45:37     INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html | 47 / 136 (34%)
[task 2019-11-22T19:45:37.705Z] 19:45:37     INFO - REFTEST TEST-LOAD | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | 47 / 136 (34%)
[task 2019-11-22T19:50:37.714Z] 19:50:37     INFO - REFTEST TEST-UNEXPECTED-FAIL | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | load failed: timed out waiting for reftest-wait to be removed
[task 2019-11-22T19:50:37.717Z] 19:50:37     INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html
[task 2019-11-22T19:50:37.718Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering AfterOnLoadScripts
[task 2019-11-22T19:50:37.719Z] 19:50:37     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2019-11-22T19:50:37.721Z] 19:50:37     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-11-22T19:50:37.722Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] RecordResult fired
[task 2019-11-22T19:50:37.723Z] 19:50:37     INFO - REFTEST INFO | Saved log: RecordResult fired
[task 2019-11-22T19:50:37.725Z] 19:50:37     INFO - REFTEST INFO | Saved log: START file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-11-22T19:50:37.726Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] OnDocumentLoad triggering WaitForTestEnd
[task 2019-11-22T19:50:37.728Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] WaitForTestEnd: Adding listeners
[task 2019-11-22T19:50:37.730Z] 19:50:37     INFO - REFTEST INFO | Saved log: Initializing canvas snapshot
[task 2019-11-22T19:50:37.731Z] 19:50:37     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-11-22T19:50:37.732Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2019-11-22T19:50:37.734Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2019-11-22T19:50:37.735Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for MozAfterPaint
[task 2019-11-22T19:50:37.737Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] HandlePendingTasksAfterMakeProgress waiting for a MozAfterPaint
[task 2019-11-22T19:50:37.738Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] AfterPaintListener in file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html
[task 2019-11-22T19:50:37.740Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] HandlePendingTasksAfterMakeProgress updating canvas
[task 2019-11-22T19:50:37.741Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] SendUpdateCanvasForEvent with 1 rects
[task 2019-11-22T19:50:37.743Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] Rect: 0 0 800 1000
[task 2019-11-22T19:50:37.745Z] 19:50:37     INFO - REFTEST INFO | Saved log: Updating canvas for invalidation
[task 2019-11-22T19:50:37.746Z] 19:50:37     INFO - REFTEST INFO | Saved log: DoDrawWindow 0,0,800,1000
[task 2019-11-22T19:50:37.748Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2019-11-22T19:50:37.749Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_TO_FIRE_INVALIDATE_EVENT
[task 2019-11-22T19:50:37.753Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: dispatching MozReftestInvalidate
[task 2019-11-22T19:50:37.754Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress
[task 2019-11-22T19:50:37.755Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: STATE_WAITING_FOR_REFTEST_WAIT_REMOVAL
[task 2019-11-22T19:50:37.756Z] 19:50:37     INFO - REFTEST INFO | Saved log: [CONTENT] MakeProgress: waiting for reftest-wait to be removed
[task 2019-11-22T19:50:37.757Z] 19:50:37     INFO - REFTEST TEST-END | file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1.html == file:///builds/worker/workspace/build/tests/reftest/tests/image/test/reftest/downscaling/downscale-moz-icon-1-ref.html

:dholbert - I have observed this on the now-abandoned debian10 test image as well as ubuntu1804 test image. Something appears to cause this test to not finish up quite nicely. Would you be able to take a look into why this may be the case?

Flags: needinfo?(dholbert)
Summary: Debian 10 - image/test/reftest/downscaling/downscale-moz-icon-1.html == image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | load failed: timed out waiting for reftest-wait to be removed → ubuntu1804 - image/test/reftest/downscaling/downscale-moz-icon-1.html == image/test/reftest/downscaling/downscale-moz-icon-1-ref.html | load failed: timed out waiting for reftest-wait to be removed

Is this consistent or intermittent failure?

These are consistent failures.

Thanks. Then I'd guess something in image/decoders/icon/gtk/nsIconChannel.cpp is happening differently in the different linux environment.

(In reply to Edwin Takahashi (:egao, :etakahashi) from comment #1)

:dholbert - I have observed this on the now-abandoned debian10 test image as well as ubuntu1804 test image. Something appears to cause this test to not finish up quite nicely. Would you be able to take a look into why this may be the case?

Sorry, I'm a bit backlogged & don't have cycles to investigate/debug this in the immediate future.

(Since this is in imagelib, perhaps aosmond might be able to take a look at some point?)

Flags: needinfo?(dholbert) → needinfo?(aosmond)

(In reply to Daniel Holbert [:dholbert] from comment #5)

(In reply to Edwin Takahashi (:egao, :etakahashi) from comment #1)

:dholbert - I have observed this on the now-abandoned debian10 test image as well as ubuntu1804 test image. Something appears to cause this test to not finish up quite nicely. Would you be able to take a look into why this may be the case?

Sorry, I'm a bit backlogged & don't have cycles to investigate/debug this in the immediate future.

(Since this is in imagelib, perhaps aosmond might be able to take a look at some point?)

Thanks :dholbert, appreciate you looking into the other reftest failures I've filed.

:aosmond - would you be able to take a look or redirect the ni to someone else? I've seen ref/crashtests not being removed as expected in the past with platforms such as macosx1014 or windows10-aarch64. Usually in those cases, I have marked the test to be skipped in the manifest. Would that be appropriate for this test, for gtkWidget once the switchover to ubuntu1804 test image is finalized?

In order to run tests against ubuntu1804 on try, please use ./mach try fuzzy --ubuntu-bionic argument.

Do you have a link handy to the failure in automation so I can look at the failing image? The one in comment 0 seems to be gone.

Flags: needinfo?(egao)

Thank you. Oh right, I forgot that it was timing out, not image comparison failure. Do you happen to have a failure log handy on a debug build?

Flags: needinfo?(egao)

Thanks, no warnings in the log point to what might be failing. To figure out what is going on it'll need some debugging.

Flags: needinfo?(tnikkel)

I see in the logs for the debug build run about rect and drawing windows. I'm not super familiar with the intricacies of reftest, though I have a bug filed at bug 1600389 and bug 1600391 that has to do with the marionette harness reporting issues with window coordinates and window sizes. I wonder if that might be playing a role.

Please re-test this after bug 1599662 lands. I have a hunch that it might fix this.

Flags: needinfo?(egao)

(In reply to Timothy Nikkel (:tnikkel) from comment #13)

Please re-test this after bug 1599662 lands. I have a hunch that it might fix this.

Certainly, I will put it on my todo/watch list. Thanks for investigating!

Flags: needinfo?(egao)

I did a try push with bug 1599662, it didn't fix it.

See Also: 1599662 →
Status: NEW → RESOLVED
Closed: 5 years ago
Resolution: --- → FIXED
Flags: needinfo?(aosmond)
You need to log in before you can comment on or make changes to this bug.