1377457 - Intermittent image/test/mochitest/test_discardAnimatedImage.html | Test timed out.

Reporter

Description

•

7 years ago

treeherder

Filed by: philringnalda [at] gmail.com

https://treeherder.mozilla.org/logviewer.html#?job_id=111067520&repo=autoland

https://queue.taskcluster.net/v1/task/BGxJUqz6SyKO_NC8zHxpew/runs/0/artifacts/public/logs/live_backing.log

Milan Sreckovic [:milan] (needinfo for best results)

Updated

•

7 years ago

Whiteboard: [gfx-noted]

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-06-26&endday=2017-07-02&tree=all

Comment hidden (Intermittent Failures Robot)

2 failures in 656 pushes (0.003 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 2

Platform breakdown:
* linux64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-07-03&endday=2017-07-09&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-07-10&endday=2017-07-16&tree=all

Milan Sreckovic [:milan] (needinfo for best results)

Updated

•

7 years ago

Priority: -- → P3

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* try: 1
* autoland: 1

Platform breakdown:
* linux64: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-07-24&endday=2017-07-30&tree=all

Comment hidden (Intermittent Failures Robot)

4 failures in 888 pushes (0.005 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 2
* try: 1
* mozilla-inbound: 1

Platform breakdown:
* linux64: 2
* linux32: 2

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-07-31&endday=2017-08-06&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 4
* mozilla-inbound: 1

Platform breakdown:
* android-4-3-armv7-api15: 2
* linux64-stylo-sequential: 1
* linux64-stylo: 1
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-08-07&endday=2017-08-13&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 7
* mozilla-inbound: 1
* mozilla-central: 1

Platform breakdown:
* android-4-3-armv7-api15: 5
* linux64: 2
* linux64-stylo-sequential: 1
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-08-14&endday=2017-08-20&tree=all

Comment hidden (Intermittent Failures Robot)

3 failures in 908 pushes (0.003 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* mozilla-inbound: 2
* mozilla-central: 1

Platform breakdown:
* linux64: 3

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-08-21&endday=2017-08-27&tree=all

Comment hidden (Intermittent Failures Robot)

3 failures in 939 pushes (0.003 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 3

Platform breakdown:
* linux64-stylo: 1
* linux64: 1
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-08-28&endday=2017-09-03&tree=all

Comment hidden (Intermittent Failures Robot)

3 failures in 924 pushes (0.003 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* mozilla-beta: 2
* mozilla-inbound: 1

Platform breakdown:
* linux64: 3

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-09-04&endday=2017-09-10&tree=all

Firefox Bug Husbandry Bot

Comment 11

•

7 years ago

Bulk priority update of open intermittent test failure bugs. 

P3 => P5

https://bugzilla.mozilla.org/show_bug.cgi?id=1381960

Priority: P3 → P5

Comment hidden (Intermittent Failures Robot)

3 failures in 1032 pushes (0.003 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 2
* mozilla-inbound: 1

Platform breakdown:
* linux64-stylo-disabled: 2
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-09-11&endday=2017-09-17&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-09-25&endday=2017-10-01&tree=all

Comment hidden (Intermittent Failures Robot)

3 failures in 824 pushes (0.004 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 2
* try: 1

Platform breakdown:
* linux64: 2
* linux32-stylo-disabled: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-10-02&endday=2017-10-08&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-central: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2017-10-09&endday=2017-10-15&tree=all

Firefox Bug Husbandry Bot

Comment 16

•

7 years ago

https://wiki.mozilla.org/Bugmasters#Intermittent_Test_Failure_Cleanup

Status: NEW → RESOLVED

Closed: 7 years ago

Resolution: --- → INCOMPLETE

Natalia Csoregi [:nataliaCs]

Comment 17

•

6 years ago

https://treeherder.mozilla.org/logviewer.html#?job_id=156980264&repo=autoland&lineNumber=3021

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* autoland: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2018-01-15&endday=2018-01-21&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-beta: 1

Platform breakdown:
* linux64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2018-02-26&endday=2018-03-04&tree=all

Comment hidden (Intermittent Failures Robot)

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

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* android-4-3-armv7-api16: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1377457&startday=2018-03-12&endday=2018-03-18&tree=all

Firefox Bug Husbandry Bot

Comment 21

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 7 years ago → 6 years ago

Resolution: --- → INCOMPLETE

Cosmin Sabou [:CosminS]

Comment 22

•

5 years ago

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=231485455&repo=mozilla-central&lineNumber=6240

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

Andreea Pavel [:apavel]

Comment 24

•

5 years ago

There are 27 total failures in the last 7 days on linux, osx and windows

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=232937185&repo=mozilla-central&lineNumber=20222

[task 2019-03-09T23:03:49.115Z] 23:03:49 INFO - TEST-START | image/test/mochitest/test_discardAnimatedImage.html
[task 2019-03-09T23:03:49.211Z] 23:03:49 INFO - GECKO(3208) | [Parent 3208, Main Thread] WARNING: Cannot set transparency mode on non-popup windows.: file /builds/worker/workspace/build/src/widget/gtk/nsWindow.cpp, line 4104
[task 2019-03-09T23:03:49.288Z] 23:03:49 INFO - GECKO(3208) | ++DOMWINDOW == 40 (0x7f91b2f9bc00) [pid = 3310] [serial = 187] [outer = 0x7f91b29892e0]
[task 2019-03-09T23:03:49.325Z] 23:03:49 INFO - GECKO(3208) | --DOMWINDOW == 39 (0x7f91b10f1000) [pid = 3310] [serial = 167] [outer = (nil)] [url = http://mochi.test:8888/tests/image/test/mochitest/test_bug865919.html]
[task 2019-03-09T23:03:49.326Z] 23:03:49 INFO - GECKO(3208) | --DOMWINDOW == 38 (0x7f91b29f0c00) [pid = 3310] [serial = 172] [outer = (nil)] [url = http://mochi.test:8888/tests/image/test/mochitest/bug89419-iframe.html]
[task 2019-03-09T23:03:49.326Z] 23:03:49 INFO - GECKO(3208) | --DOMWINDOW == 37 (0x7f91b29f2400) [pid = 3310] [serial = 171] [outer = (nil)] [url = http://mochi.test:8888/tests/image/test/mochitest/bug89419-iframe.html]

task 2019-03-09T23:09:15.372Z] 23:09:15 [task 2019-03-09T23:09:15.374Z] 23:09:15 [task 2019-03-09T23:09:15.375Z] 23:09:15 [task 2019-03-09T23:09:15.375Z] 23:09:15 [task 2019-03-09T23:09:15.375Z] 23:09:15 [task 2019-03-09T23:09:15.376Z] 23:09:15 [task 2019-03-09T23:09:15.376Z] 23:09:15 [task 2019-03-09T23:09:15.376Z] 23:09:15 [task 2019-03-09T23:09:15.376Z] 23:09:15 [task 2019-03-09T23:09:15.377Z] 23:09:15 [task 2019-03-09T23:09:15.377Z] 23:09:15 [task 2019-03-09T23:09:15.378Z] 23:09:15 [task 2019-03-09T23:09:15.379Z] 23:09:15 [task 2019-03-09T23:09:15.379Z] 23:09:15 [task 2019-03-09T23:09:15.380Z] 23:09:15 [task 2019-03-09T23:09:15.380Z] 23:09:15 [task 2019-03-09T23:09:15.380Z] 23:09:15 [task 2019-03-09T23:09:15.381Z] 23:09:15 [task 2019-03-09T23:09:15.382Z] 23:09:15 [task 2019-03-09T23:09:15.382Z] 23:09:15 [task 2019-03-09T23:09:15.383Z] 23:09:15 [task 2019-03-09T23:09:15.383Z] 23:09:15 [task 2019-03-09T23:09:15.383Z] 23:09:15 [task 2019-03-09T23:09:15.384Z] 23:09:15 [task 2019-03-09T23:09:15.384Z] 23:09:15 [task 2019-03-09T23:09:15.384Z] 23:09:15 [task 2019-03-09T23:09:15.385Z] 23:09:15 [task 2019-03-09T23:09:15.385Z] 23:09:15 [task 2019-03-09T23:09:15.385Z] 23:09:15 [task 2019-03-09T23:09:15.385Z] 23:09:15 [task 2019-03-09T23:09:15.386Z] 23:09:15 [task 2019-03-09T23:09:15.387Z] 23:09:15 [task 2019-03-09T23:09:15.387Z] 23:09:15 [task 2019-03-09T23:09:15.387Z] 23:09:15 [task 2019-03-09T23:09:15.927Z] 23:09:15 [task 2019-03-09T23:09:15.928Z] 23:09:15 INFO - TEST-PASS | image/test/mochitest/test_discardAnimatedImage.html | got image discard
INFO - TEST-PASS | image/test/mochitest/test_discardAnimatedImage.html | got image discard
INFO - Buffered messages finished
INFO - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_discardAnimatedImage.html | Test timed out.
INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
INFO - reportError@SimpleTest/TestRunner.js:121:22
INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
INFO - RunSet.runtests@SimpleTest/setup.js:201:14
INFO - RunSet.runall@SimpleTest/setup.js:180:12
INFO - hookupTests@SimpleTest/setup.js:273:12
INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
INFO - hookup@SimpleTest/setup.js:253:5
INFO - EventHandlerNonNull@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
INFO - GECKO(3208) | MEMORY STAT | vsize 1515MB | residentFast 135MB | heapAllocated 12MB
INFO - TEST-OK | image/test/mochitest/test_discardAnimatedImage.html | took 326814ms

Andrew can you assign someone to take a look at this?

Flags: needinfo?(aosmond)

Whiteboard: [gfx-noted] → [gfx-noted][stockwell needswork:owner]

Comment hidden (Intermittent Failures Robot)

Stefan Hindli [:stefan_hindli]

Comment 27

•

5 years ago

Over the last 7 days there have been 32 failures present on this log. These happen on windows10-64-qr, windows10-64-pgo-qr, windows10-64, osx-10-10, linux64-qr, linux64, linux32

https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=235634385&repo=autoland&lineNumber=7054

Timothy Nikkel (:tnikkel)

Assignee

Comment 28

•

5 years ago

The test times out waiting for the discard notification of an image or images. The reason they don't get discarded is they have a lock count of 1. I tried giving the images a unique query string so this test was the only place they were used, that seemed to reduce the frequency about 4x, but it still happens. So some weird bug where images get locked and stay locked (but shouldn't be).

Comment hidden (Intermittent Failures Robot)

Cristina Coroiu [:ccoroiu]

Comment 30

•

5 years ago

In the last 7 days, there have been 37 failures.

Most of the failures are on linux32 and linux64, linux64-qr, linux64-shippable, osx-10-10, osx-10-10-shippable and windows10-64.
Affected build types: debug, opt, asan.

An example of a recent log file:
https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=235804227&repo=autoland&lineNumber=3685

And the relevant part of the log:
[task 2019-03-25T10:21:02.090Z] 10:21:02 INFO - TEST-PASS | image/test/mochitest/test_discardAnimatedImage.html | got image discard
[task 2019-03-25T10:21:02.091Z] 10:21:02 INFO - Buffered messages finished
[task 2019-03-25T10:21:02.094Z] 10:21:02 INFO - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_discardAnimatedImage.html | Test timed out.
[task 2019-03-25T10:21:02.095Z] 10:21:02 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-03-25T10:21:02.095Z] 10:21:02 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-03-25T10:21:02.096Z] 10:21:02 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-03-25T10:21:02.096Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.097Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.097Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.098Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.098Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.098Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.099Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.099Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.100Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.100Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.101Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.101Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.101Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.102Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.102Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.103Z] 10:21:02 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-03-25T10:21:02.103Z] 10:21:02 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
[task 2019-03-25T10:21:02.103Z] 10:21:02 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - hookup@SimpleTest/setup.js:253:5
[task 2019-03-25T10:21:02.104Z] 10:21:02 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1

Andrew, as you are the triage owner of this component, could you please take a look at this?
Thank you!

Comment hidden (Intermittent Failures Robot)

Timothy Nikkel (:tnikkel)

Assignee

Comment 32

•

5 years ago

I've been debugging this via try, see comment 28. But didn't have any time last week to do more try pushes. I'll try to pick this back up soon.

Comment hidden (Intermittent Failures Robot)

Timothy Nikkel (:tnikkel)

Assignee

Comment 34

•

5 years ago

I figured this out, hopefully patch tomorrow.

Andreea Pavel [:apavel]

Comment 35

•

5 years ago

Timothy, that's great to hear, thank you.

Flags: needinfo?(aosmond)

Comment hidden (Intermittent Failures Robot)

Cosmin Sabou [:CosminS]

Comment 37

•

5 years ago

This has failed 35 times in the last 7 days: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-04-09&endday=2019-04-16&tree=trunk&bug=1377457

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=240515931&repo=autoland

[task 2019-04-16T00:33:19.147Z] 00:33:19 INFO - TEST-PASS | image/test/mochitest/test_discardAnimatedImage.html | got image discard
[task 2019-04-16T00:33:19.148Z] 00:33:19 INFO - Buffered messages finished
[task 2019-04-16T00:33:19.151Z] 00:33:19 INFO - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_discardAnimatedImage.html | Test timed out.
[task 2019-04-16T00:33:19.152Z] 00:33:19 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-04-16T00:33:19.153Z] 00:33:19 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-04-16T00:33:19.153Z] 00:33:19 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-04-16T00:33:19.153Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.154Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.154Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.155Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.156Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.156Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.157Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.157Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.158Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.158Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.159Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.159Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.159Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.160Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.160Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.162Z] 00:33:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-16T00:33:19.162Z] 00:33:19 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
[task 2019-04-16T00:33:19.163Z] 00:33:19 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
[task 2019-04-16T00:33:19.163Z] 00:33:19 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-04-16T00:33:19.163Z] 00:33:19 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-04-16T00:33:19.163Z] 00:33:19 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-04-16T00:33:19.164Z] 00:33:19 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
[task 2019-04-16T00:33:19.164Z] 00:33:19 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
[task 2019-04-16T00:33:19.165Z] 00:33:19 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
[task 2019-04-16T00:33:19.165Z] 00:33:19 INFO - hookup@SimpleTest/setup.js:253:5
[task 2019-04-16T00:33:19.165Z] 00:33:19 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-04-16T00:33:19.543Z] 00:33:19 INFO - GECKO(2916) | MEMORY STAT | vsize 2579MB | residentFast 135MB | heapAllocated 11MB
[task 2019-04-16T00:33:19.559Z] 00:33:19 INFO - TEST-OK | image/test/mochitest/test_discardAnimatedImage.html | took 306308ms

Timothy, any progress on this? Thank you.

Flags: needinfo?(tnikkel)

Comment hidden (Intermittent Failures Robot)

Razvan Maries

Comment 39

•

5 years ago

There are 27 total failures in the last 7 days on multiple platforms: https://treeherder.mozilla.org/intermittent-failures.html#/bugdetails?startday=2019-04-17&endday=2019-04-24&tree=trunk&bug=1377457

Recent failure log: https://treeherder.mozilla.org/logviewer.html#/jobs?job_id=242172785&repo=mozilla-central&lineNumber=20185

[task 2019-04-23T23:23:13.197Z] 23:23:13 INFO - TEST-START | image/test/mochitest/test_discardAnimatedImage.html
[task 2019-04-23T23:28:19.294Z] 23:28:19 INFO - Buffered messages finished
[task 2019-04-23T23:28:19.295Z] 23:28:19 INFO - TEST-UNEXPECTED-FAIL | image/test/mochitest/test_discardAnimatedImage.html | Test timed out.
[task 2019-04-23T23:28:19.295Z] 23:28:19 INFO - SimpleTest.ok@SimpleTest/SimpleTest.js:275:18
[task 2019-04-23T23:28:19.295Z] 23:28:19 INFO - reportError@SimpleTest/TestRunner.js:121:22
[task 2019-04-23T23:28:19.295Z] 23:28:19 INFO - TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
[task 2019-04-23T23:28:19.296Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.296Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.296Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.296Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.297Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.297Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.297Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.297Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.297Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.298Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.298Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.298Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.298Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.299Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.299Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.299Z] 23:28:19 INFO - setTimeout handlerTestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
[task 2019-04-23T23:28:19.299Z] 23:28:19 INFO - TestRunner.runTests/<@SimpleTest/TestRunner.js:381:20
[task 2019-04-23T23:28:19.300Z] 23:28:19 INFO - promise callbackTestRunner.runTests@SimpleTest/TestRunner.js:368:50
[task 2019-04-23T23:28:19.300Z] 23:28:19 INFO - RunSet.runtests@SimpleTest/setup.js:201:14
[task 2019-04-23T23:28:19.300Z] 23:28:19 INFO - RunSet.runall@SimpleTest/setup.js:180:12
[task 2019-04-23T23:28:19.300Z] 23:28:19 INFO - hookupTests@SimpleTest/setup.js:273:12
[task 2019-04-23T23:28:19.301Z] 23:28:19 INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
[task 2019-04-23T23:28:19.301Z] 23:28:19 INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
[task 2019-04-23T23:28:19.301Z] 23:28:19 INFO - EventHandlerNonNullgetTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
[task 2019-04-23T23:28:19.301Z] 23:28:19 INFO - hookup@SimpleTest/setup.js:253:5
[task 2019-04-23T23:28:19.302Z] 23:28:19 INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
[task 2019-04-23T23:28:19.722Z] 23:28:19 INFO - GECKO(2934) | MEMORY STAT | vsize 2582MB | residentFast 133MB | heapAllocated 11MB

Comment hidden (Intermittent Failures Robot)

Timothy Nikkel (:tnikkel)

Assignee

Comment 41

•

5 years ago

Attached file Bug 1377457. Only apply locks to the image if SetHasImage has been called. r?aosmond — Details

This was observed in an intermittent failure of image/test/mochitest/test_discardAnimatedImage.html. What happened was:

Document::MaybePreLoadImage was called for the images in the test.
imgRequest::OnDataAvailable is called on at least one of the images. This creates the RasterImage, so any proxy for this imgRequest will now return the image via GetImage(). imgRequest::OnDataAvailable also queues the FinishPreparingForNewPartRunnable back to the main thread to call OnImageAvailable on the progress tracker on the main thread.
We get the actual LoadImage calls for the images of the document. We create new proxies for the existing imgRequests. imgRequestProxy::Init calls mBehaviour->SetOwner(aOwner), which sets mOwnerHasImage to true because the progress tracker has an mImage (the one we created above).
We get a call to LockImage, this gets forwarded to the RasterImage because mOwnerHasImage is true and we can access the image.
The FinishPreparingForNewPartRunnable finally runs on the main thread. The OnImageAvailable notification from the progress tracker ends up in imgRequestProxy::SetHasImage. imgRequestProxy::SetHasImage applies our local count mLockCount to the RasterImage, even though we've already forwarded one of those LockImage calls to the image. LockImage calls are now unbalanced and the image will always remain locked.

The fix is simple. Only apply the Lock/Unlock calls if the FinishPreparingForNewPartRunnable has hit the main thread (ie ignore an image we can access until this happens).

Timothy Nikkel (:tnikkel)

Assignee

Updated

•

5 years ago

Assignee: nobody → tnikkel

Flags: needinfo?(tnikkel)

Pulsebot

Comment 42

•

5 years ago

Pushed by tnikkel@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/5acce9aaceb9
Only apply locks to the image if SetHasImage has been called. r=aosmond

Cristian Brindusan [:cbrindusan]

Comment 43

•

5 years ago

bugherder

https://hg.mozilla.org/mozilla-central/rev/5acce9aaceb9

Status: REOPENED → RESOLVED

Closed: 6 years ago → 5 years ago

status-firefox68: --- → fixed

Resolution: --- → FIXED

Target Milestone: --- → mozilla68