1603744 - Intermittent image/test/mochitest/test_animated_gif.html | `loadComplete` should be received once - got +0, expected 1

Status: RESOLVED FIXED

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

Description

[Treeherder Bug Filer]

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

---

[task 2019-12-13T13:42:29.221Z] 13:42:29 INFO - TEST-START | image/test/mochitest/test_animated_gif.html
[task 2019-12-13T13:42:29.298Z] 13:42:29 INFO - GECKO(1118) | [Parent 1118, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-13T13:42:29.300Z] 13:42:29 INFO - GECKO(1118) | [Child 1298, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-13T13:42:29.538Z] 13:42:29 INFO - GECKO(1118) | [Child 1298, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-13T13:42:29.538Z] 13:42:29 INFO - GECKO(1118) | [Child 1298, Main Thread] WARNING: Trying to request nsIHttpChannel from DocumentChannelChild, this is likely broken: file /builds/worker/workspace/build/src/netwerk/ipc/DocumentChannelChild.cpp, line 63
[task 2019-12-13T13:42:29.538Z] 13:42:29 INFO - GECKO(1118) | [Child 1298, Main Thread] WARNING: NS_ENSURE_TRUE(request) failed: file /builds/worker/workspace/build/src/netwerk/base/nsLoadGroup.cpp, line 574
[task 2019-12-13T13:42:29.538Z] 13:42:29 INFO - GECKO(1118) | [Parent 1118, Main Thread] WARNING: we only accept nsIURI interface type, patch welcome: file /builds/worker/workspace/build/src/dom/ipc/PropertyBagUtils.cpp, line 112
[task 2019-12-13T13:42:29.546Z] 13:42:29 INFO - GECKO(1118) | [Child 1298, Main Thread] WARNING: NS_ENSURE_TRUE(currentInner) failed: file /builds/worker/workspace/build/src/dom/base/WindowDestroyedEvent.cpp, line 106
[task 2019-12-13T13:42:29.574Z] 13:42:29 INFO - GECKO(1118) | ### XPCOM_MEM_BLOAT_LOG defined -- logging bloat/leaks to /tmp/tmp9pCovX.mozrunner/runtests_leaks_tab_pid1359.log
[task 2019-12-13T13:42:29.751Z] 13:42:29 INFO - GECKO(1118) | [Child 1359, Main Thread] WARNING: No CID found when attempting to map contract ID: file /builds/worker/workspace/build/src/xpcom/components/nsComponentManager.cpp, line 727
[task 2019-12-13T13:42:29.948Z] 13:42:29 INFO - GECKO(1118) | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2019-12-13T13:42:30.189Z] 13:42:30 INFO - GECKO(1118) | [Child 1359, Main Thread] WARNING: could not set real-time limit at process startup: file /builds/worker/workspace/build/src/dom/ipc/ContentChild.cpp, line 1819
[task 2019-12-13T13:42:30.267Z] 13:42:30 INFO - GECKO(1118) | [Child 1359, Main Thread] WARNING: NS_ENSURE_TRUE(mPresShell) failed: file /builds/worker/workspace/build/src/layout/base/nsPresContext.cpp, line 848
[task 2019-12-13T13:42:30.484Z] 13:42:30 INFO - TEST-INFO | started process screentopng
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - TEST-INFO | screentopng: exit 0
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_animated_gif.html | loadComplete should be received once - got +0, expected 1
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - SimpleTest.is@SimpleTest/SimpleTest.js:325:16
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - @image/test/mochitest/test_animated_gif.html:36:7
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - moveOn@SimpleTest/SimpleTest.js:1064:49
[task 2019-12-13T13:42:31.076Z] 13:42:31 INFO - SimpleTest.waitForCondition/interval<@SimpleTest/SimpleTest.js:1060:7
[task 2019-12-13T13:42:31.077Z] 13:42:31 INFO - setInterval handler*SimpleTest.waitForCondition@SimpleTest/SimpleTest.js:1046:29
[task 2019-12-13T13:42:31.077Z] 13:42:31 INFO - @image/test/mochitest/test_animated_gif.html:33:14
[task 2019-12-13T13:42:31.077Z] 13:42:31 INFO - GECKO(1118) | MEMORY STAT | vsize 2544MB | residentFast 160MB | heapAllocated 19MB

Comment 1

[Cristina Coroiu [:ccoroiu]]

Started with this push: https://hg.mozilla.org/integration/autoland/rev/c400f7480696ce9fce0234f857a94965536c0567

:hiro can you please take a look?

Comment 2

[Timothy Nikkel (:tnikkel)]

I think we should change the test to wait until it receives loadComplete and then wait 1 second before finishing to make sure we don't get any decodeComplete's in that time.

Comment 3

[Hiroyuki Ikezoe (:hiro)]

Thanks! I will do the way.

Comment 4

[Hiroyuki Ikezoe (:hiro)]

https://treeherder.mozilla.org/#/jobs?repo=try&revision=6d466f625e3b4d450bab4ec749dcc5b1dd42294f

Comment 5

[Hiroyuki Ikezoe (:hiro)]

Attachment: Bug 1603744 - Make test_animated_gif.html reliable. r?tnikkel

Now the test runs following steps;

1. Start the test
2. Set iframe's src attribute to load the iframe document
3. Waits for a loadComplete message from the iframe
4. Waits 1 second to make sure decodeComplete doesn't happen in the iframe

Comment 6

[Pulsebot]

Pushed by [e-mail]:
https://hg.mozilla.org/integration/autoland/rev/a9429f3525f0
Make test_animated_gif.html reliable. r=tnikkel

Comment 7

[Dorel Luca [:dluca]]

https://hg.mozilla.org/mozilla-central/rev/a9429f3525f0

Comment 8

[Intermittent Failures Robot]

6 failures in 4867 pushes (0.001 failures/push) were associated with this bug in the last 7 days.

Repository breakdown:

• autoland: 5
• mozilla-central: 1

Platform breakdown:

• linux64-qr: 6

For more details, see:
https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?bug=1603744&startday=2019-12-09&endday=2019-12-15&tree=all