1314197 - Intermittent layout/base/tests/test_bug607529.html | Test timed out.

Status: RESOLVED INCOMPLETE

(Core :: Layout, defect)

Description

[Treeherder Bug Filer]

Filed by: philringnalda [at] gmail.com

https://treeherder.mozilla.org/logviewer.html#?job_id=38499899&repo=mozilla-inbound

https://queue.taskcluster.net/v1/task/JrHt4ndQTdiZnzDmoZMbKw/runs/0/artifacts/public%2Flogs%2Flive_backing.log

Comment 1

[Cosmin Sabou [:CosminS]]

https://treeherder.mozilla.org/#/jobs?repo=autoland&fromchange=f58dca05f26f69116bf5a736d384e97963beec61&selectedJob=152701590&filter-searchStr=201d323cb8182bf266733d426e7e2a341ead7060

Comment 2

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 6

Platform breakdown:
* linux64-qr: 6

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

Comment 3

[Intermittent Failures Robot]

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

Repository breakdown:
* autoland: 3
* mozilla-central: 1

Platform breakdown:
* linux64-qr: 4

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1314197&startday=2017-12-25&endday=2017-12-31&tree=all

Comment 4

[Intermittent Failures Robot]

25 failures in 462 pushes (0.054 failures/push) were associated with this bug in the last 7 days. 

This is the #38 most frequent failure this week.   

Repository breakdown:
* autoland: 12
* mozilla-inbound: 10
* try: 2
* mozilla-central: 1

Platform breakdown:
* linux64-qr: 24
* osx-10-10: 1

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

Comment 5

[Eliza Balazs [:ebalazs_]]

There are 37 failures in the last 7 days.
They occur on linux64-qr opt, OS 10.10 debug, Windows 7 opt.
A recent log example: https://treeherder.mozilla.org/logviewer.html#?repo=autoland&job_id=154945253&lineNumber=8569
And a relevant part of the log:
[task 2018-01-09T06:22:09.443Z] 06:22:09     INFO - TEST-START | layout/base/tests/test_bug607529.html
8562
[task 2018-01-09T06:27:11.310Z] 06:27:11     INFO - TEST-INFO | started process screentopng
8563
[task 2018-01-09T06:27:11.753Z] 06:27:11     INFO - TEST-INFO | screentopng: exit 0
8564
[task 2018-01-09T06:27:11.755Z] 06:27:11     INFO - Buffered messages logged at 06:22:09
8565
[task 2018-01-09T06:27:11.757Z] 06:27:11     INFO - TEST-PASS | layout/base/tests/test_bug607529.html | Should never end up not being cached 
8566
[task 2018-01-09T06:27:11.759Z] 06:27:11     INFO - TEST-PASS | layout/base/tests/test_bug607529.html | Should never end up not being cached 
8567
[task 2018-01-09T06:27:11.761Z] 06:27:11     INFO - TEST-PASS | layout/base/tests/test_bug607529.html | Should never end up not being cached 
8568
[task 2018-01-09T06:27:11.763Z] 06:27:11     INFO - Buffered messages finished
8569
[task 2018-01-09T06:27:11.764Z] 06:27:11     INFO - TEST-UNEXPECTED-FAIL | layout/base/tests/test_bug607529.html | Test timed out. 
8570
[task 2018-01-09T06:27:11.766Z] 06:27:11     INFO -     reportError@SimpleTest/TestRunner.js:121:7
8571
[task 2018-01-09T06:27:11.767Z] 06:27:11     INFO -     TestRunner._checkForHangs@SimpleTest/TestRunner.js:142:7
8572
[task 2018-01-09T06:27:11.769Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8573
[task 2018-01-09T06:27:11.770Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8574
[task 2018-01-09T06:27:11.772Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8575
[task 2018-01-09T06:27:11.773Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8576
[task 2018-01-09T06:27:11.775Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8577
[task 2018-01-09T06:27:11.776Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8578
[task 2018-01-09T06:27:11.777Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8579
[task 2018-01-09T06:27:11.778Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8580
[task 2018-01-09T06:27:11.780Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8581
[task 2018-01-09T06:27:11.781Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8582
[task 2018-01-09T06:27:11.782Z] 06:27:11     INFO -     setTimeout handler*TestRunner._checkForHangs@SimpleTest/TestRunner.js:163:5
8583
[task 2018-01-09T06:27:11.784Z] 06:27:11     INFO -     TestRunner.runTests@SimpleTest/TestRunner.js:380:5
8584
[task 2018-01-09T06:27:11.785Z] 06:27:11     INFO -     RunSet.runtests@SimpleTest/setup.js:194:3
8585
[task 2018-01-09T06:27:11.786Z] 06:27:11     INFO -     RunSet.runall@SimpleTest/setup.js:173:5
8586
[task 2018-01-09T06:27:11.788Z] 06:27:11     INFO -     hookupTests@SimpleTest/setup.js:266:5
8587
[task 2018-01-09T06:27:11.789Z] 06:27:11     INFO - parseTestManifest@http://mochi.test:8888/manifestLibrary.js:36:5
8588
[task 2018-01-09T06:27:11.790Z] 06:27:11     INFO - getTestManifest/req.onload@http://mochi.test:8888/manifestLibrary.js:49:11
8589
[task 2018-01-09T06:27:11.791Z] 06:27:11     INFO - EventHandlerNonNull*getTestManifest@http://mochi.test:8888/manifestLibrary.js:45:3
8590
[task 2018-01-09T06:27:11.793Z] 06:27:11     INFO -     hookup@SimpleTest/setup.js:246:5
8591
[task 2018-01-09T06:27:11.794Z] 06:27:11     INFO - EventHandlerNonNull*@http://mochi.test:8888/tests?autorun=1&closeWhenDone=1&consoleLevel=INFO&manifestFile=tests.json&dumpOutputDirectory=%2Ftmp&cleanupCrashes=true:11:1
8592
[task 2018-01-09T06:27:12.316Z] 06:27:12     INFO - GECKO(6836) | MEMORY STAT | vsize 1678MB | residentFast 146MB | heapAllocated 28MB
8593
[task 2018-01-09T06:27:14.319Z] 06:27:14     INFO - TEST-OK | layout/base/tests/test_bug607529.html | took 304878ms
8594
[task 2018-01-09T06:27:17.342Z] 06:27:17     INFO - Error: Unable to restore focus, expect failures and timeouts.
:jet, can you please a have a look?

Comment 6

[Jet Villegas (inactive)]

This test is asserting that RequestAnimationFrame() correctly handles document lifetimes. ::RevokeAnimationFrameNotifications() is how it does this. I'm not sure why this is failing more often with WebRender. 

Jeff: WDYT?

Comment 7

[Jeff Muizelaar [:jrmuizel]]

WebRender has somewhat broken requestAnimationFrame() handling right now so it's believable that this could be intermittent. Bug 1419851 will get us into a better state.

Comment 8

[Hiroyuki Ikezoe (:hiro)]

As far as I know, bug 1419851 is not related to requestAnimationFrame, the bug is related to mozAfterPaint event handling.

Comment 9

[Kartikaya Gupta (email:[e-mail])]

The problem is with invalidation detection, which affects both requestAnimationFrame and mozAfterPaint dispatching.

Comment 10

[Hiroyuki Ikezoe (:hiro)]

Oh I did not know that invalidation affects requestAnimationFrame.

Comment 11

[Intermittent Failures Robot]

42 failures in 788 pushes (0.053 failures/push) were associated with this bug in the last 7 days. 

This is the #36 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:
* mozilla-inbound: 21
* autoland: 19
* try: 2

Platform breakdown:
* linux64-qr: 41
* windows7-32: 1

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

Comment 12

[Kartikaya Gupta (email:[e-mail])]

If needed we can disable this test (with skip-if = webrender) until we have a fix for the invalidation problems.

Comment 13

[Intermittent Failures Robot]

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

This is the #48 most frequent failure this week.   

Repository breakdown:
* mozilla-inbound: 12
* autoland: 7
* mozilla-central: 3
* try: 1

Platform breakdown:
* linux64-qr: 23

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

Comment 14

[Intermittent Failures Robot]

20 failures in 701 pushes (0.029 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* autoland: 9
* mozilla-inbound: 8
* mozilla-central: 3

Platform breakdown:
* linux64-qr: 20

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

Comment 15

[Intermittent Failures Robot]

8 failures in 735 pushes (0.011 failures/push) were associated with this bug in the last 7 days.    

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

Platform breakdown:
* linux64-qr: 8

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

Comment 16

[Intermittent Failures Robot]

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

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

Platform breakdown:
* linux64-qr: 5

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1314197&startday=2018-02-05&endday=2018-02-11&tree=all

Comment 17

[Firefox Bug Husbandry Bot]

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